2023-10-03T21:14:30.231Z,1696367670.231 [Supervisor](DEBUG): Initializing supervisor. 2023-10-03T21:14:30.236Z,1696367670.236 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-10-03T21:14:30.236Z,1696367670.236 [SyncHandler](INFO): Protected caller Thread ID is 2460 2023-10-03T21:14:30.237Z,1696367670.237 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-10-03T21:14:30.238Z,1696367670.238 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-10-03T21:14:30.238Z,1696367670.238 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2461 2023-10-03T21:14:30.242Z,1696367670.242 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-10-03T21:14:30.260Z,1696367670.260 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-10-03T21:14:30.261Z,1696367670.261 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-10-03T21:14:30.262Z,1696367670.262 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2462 2023-10-03T21:14:30.266Z,1696367670.266 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-10-03T21:14:30.267Z,1696367670.267 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-10-03T21:14:30.267Z,1696367670.267 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2463 2023-10-03T21:14:30.269Z,1696367670.269 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-10-03T21:14:30.270Z,1696367670.270 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-10-03T21:14:30.271Z,1696367670.271 [logger ThreadHandler](INFO): Protected caller Thread ID is 2464 2023-10-03T21:14:30.274Z,1696367670.274 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-10-03T21:14:30.275Z,1696367670.275 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-10-03T21:14:30.276Z,1696367670.276 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-10-03T21:14:30.603Z,1696367670.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-10-03T21:14:30.604Z,1696367670.604 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-10-03T21:14:30.684Z,1696367670.684 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-10-03T21:14:30.793Z,1696367670.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-10-03T21:14:30.794Z,1696367670.794 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-10-03T21:14:30.923Z,1696367670.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-10-03T21:14:30.923Z,1696367670.923 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-10-03T21:14:31.267Z,1696367671.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-10-03T21:14:31.268Z,1696367671.268 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-10-03T21:14:31.365Z,1696367671.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-10-03T21:14:31.366Z,1696367671.366 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-10-03T21:14:31.763Z,1696367671.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-10-03T21:14:31.764Z,1696367671.764 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-10-03T21:14:31.975Z,1696367671.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-10-03T21:14:31.976Z,1696367671.976 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-10-03T21:14:32.055Z,1696367672.055 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-10-03T21:14:32.286Z,1696367672.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-10-03T21:14:32.286Z,1696367672.286 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-10-03T21:14:32.506Z,1696367672.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-10-03T21:14:32.506Z,1696367672.506 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-10-03T21:14:33.018Z,1696367673.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-10-03T21:14:33.019Z,1696367673.019 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-10-03T21:14:33.212Z,1696367673.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-10-03T21:14:33.212Z,1696367673.212 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-10-03T21:14:33.499Z,1696367673.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-10-03T21:14:33.500Z,1696367673.500 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-10-03T21:14:34.328Z,1696367674.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-10-03T21:14:34.328Z,1696367674.328 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-10-03T21:14:34.855Z,1696367674.855 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-10-03T21:14:34.858Z,1696367674.858 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2023-10-03T21:14:34.858Z,1696367674.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2023-10-03T21:14:34.981Z,1696367674.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2023-10-03T21:14:35.107Z,1696367675.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2023-10-03T21:14:35.192Z,1696367675.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2023-10-03T21:14:35.303Z,1696367675.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2023-10-03T21:14:35.404Z,1696367675.404 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2023-10-03T21:14:35.734Z,1696367675.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-10-03T21:14:35.734Z,1696367675.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2023-10-03T21:14:35.851Z,1696367675.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2023-10-03T21:14:35.948Z,1696367675.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2023-10-03T21:14:36.054Z,1696367676.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2023-10-03T21:14:36.188Z,1696367676.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2023-10-03T21:14:36.276Z,1696367676.276 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-10-03T21:14:36.283Z,1696367676.283 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-10-03T21:14:36.381Z,1696367676.381 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-10-03T21:14:36.381Z,1696367676.381 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-10-03T21:14:36.430Z,1696367676.430 [VerticalControl](DEBUG): Construct VerticalControl. 2023-10-03T21:14:36.492Z,1696367676.492 [VerticalControl] Loaded 2023-10-03T21:14:36.492Z,1696367676.492 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-10-03T21:14:36.495Z,1696367676.495 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-10-03T21:14:36.537Z,1696367676.537 [HorizontalControl] Loaded 2023-10-03T21:14:36.537Z,1696367676.537 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-10-03T21:14:36.539Z,1696367676.539 [SpeedControl](DEBUG): Construct SpeedControl. 2023-10-03T21:14:36.542Z,1696367676.542 [SpeedControl] Loaded 2023-10-03T21:14:36.543Z,1696367676.543 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-10-03T21:14:36.545Z,1696367676.545 [LoopControl](DEBUG): Construct LoopControl. 2023-10-03T21:14:36.546Z,1696367676.546 [LoopControl] Loaded 2023-10-03T21:14:36.546Z,1696367676.546 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-10-03T21:14:36.547Z,1696367676.547 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-10-03T21:14:36.547Z,1696367676.547 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-10-03T21:14:36.601Z,1696367676.601 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-10-03T21:14:36.602Z,1696367676.602 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-10-03T21:14:37.015Z,1696367677.015 [AHRS_M2] Loaded 2023-10-03T21:14:37.016Z,1696367677.016 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-10-03T21:14:37.051Z,1696367677.051 [BackseatComponent] Loaded 2023-10-03T21:14:37.052Z,1696367677.052 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-10-03T21:14:37.053Z,1696367677.053 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408814E0 2023-10-03T21:14:37.053Z,1696367677.053 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2547 2023-10-03T21:14:37.056Z,1696367677.056 [LcmUniversalReporter] Loaded 2023-10-03T21:14:37.056Z,1696367677.056 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-10-03T21:14:37.866Z,1696367677.866 [BPC1] Loaded 2023-10-03T21:14:37.867Z,1696367677.867 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-10-03T21:14:37.939Z,1696367677.939 [DataOverHttps] Loaded 2023-10-03T21:14:37.939Z,1696367677.939 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-10-03T21:14:37.940Z,1696367677.940 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2023-10-03T21:14:37.941Z,1696367677.941 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2548 2023-10-03T21:14:37.962Z,1696367677.962 [Depth_Keller] Loaded 2023-10-03T21:14:37.962Z,1696367677.962 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-10-03T21:14:37.967Z,1696367677.967 [DropWeight] Loaded 2023-10-03T21:14:37.967Z,1696367677.967 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-10-03T21:14:38.031Z,1696367678.031 [NAL9602] Loaded 2023-10-03T21:14:38.031Z,1696367678.031 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-10-03T21:14:38.061Z,1696367678.061 [Onboard] Loaded 2023-10-03T21:14:38.062Z,1696367678.062 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-10-03T21:14:38.063Z,1696367678.063 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0 2023-10-03T21:14:38.063Z,1696367678.063 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2549 2023-10-03T21:14:38.075Z,1696367678.075 [Power24vConverter] Loaded 2023-10-03T21:14:38.075Z,1696367678.075 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-10-03T21:14:38.089Z,1696367678.089 [Radio_Surface] Loaded 2023-10-03T21:14:38.089Z,1696367678.089 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-10-03T21:14:38.090Z,1696367678.090 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409114E0 2023-10-03T21:14:38.090Z,1696367678.090 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2550 2023-10-03T21:14:38.183Z,1696367678.183 [DAT] Loaded 2023-10-03T21:14:38.184Z,1696367678.184 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-10-03T21:14:38.185Z,1696367678.185 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409414E0 2023-10-03T21:14:38.185Z,1696367678.185 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2551 2023-10-03T21:14:38.209Z,1696367678.209 [Waterlinked] Loaded 2023-10-03T21:14:38.209Z,1696367678.209 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-10-03T21:14:38.209Z,1696367678.209 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-10-03T21:14:38.210Z,1696367678.210 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-10-03T21:14:38.220Z,1696367678.220 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-10-03T21:14:38.220Z,1696367678.220 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-10-03T21:14:38.246Z,1696367678.246 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-10-03T21:14:38.247Z,1696367678.247 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-10-03T21:14:38.380Z,1696367678.380 [SBIT](DEBUG): Construct Startup Built In Test. 2023-10-03T21:14:38.390Z,1696367678.390 [SBIT] Loaded 2023-10-03T21:14:38.390Z,1696367678.390 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-10-03T21:14:38.393Z,1696367678.393 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-10-03T21:14:38.406Z,1696367678.406 [IBIT] Loaded 2023-10-03T21:14:38.406Z,1696367678.406 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-10-03T21:14:38.412Z,1696367678.412 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-10-03T21:14:38.518Z,1696367678.518 [CBIT] Loaded 2023-10-03T21:14:38.519Z,1696367678.519 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-10-03T21:14:38.519Z,1696367678.519 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-10-03T21:14:38.520Z,1696367678.520 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-10-03T21:14:38.816Z,1696367678.816 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-10-03T21:14:38.816Z,1696367678.816 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-10-03T21:14:38.864Z,1696367678.864 [DepthRateCalculator] Loaded 2023-10-03T21:14:38.865Z,1696367678.865 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-10-03T21:14:38.870Z,1696367678.870 [PitchRateCalculator] Loaded 2023-10-03T21:14:38.870Z,1696367678.870 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-10-03T21:14:38.880Z,1696367678.880 [SpeedCalculator] Loaded 2023-10-03T21:14:38.881Z,1696367678.881 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-10-03T21:14:38.885Z,1696367678.885 [YawRateCalculator] Loaded 2023-10-03T21:14:38.885Z,1696367678.885 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-10-03T21:14:38.904Z,1696367678.904 [ElevatorOffsetCalculator] Loaded 2023-10-03T21:14:38.904Z,1696367678.904 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-10-03T21:14:38.905Z,1696367678.905 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-10-03T21:14:38.905Z,1696367678.905 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-10-03T21:14:39.051Z,1696367679.051 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-10-03T21:14:39.052Z,1696367679.052 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-10-03T21:14:39.163Z,1696367679.163 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-10-03T21:14:39.163Z,1696367679.163 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-10-03T21:14:39.207Z,1696367679.207 [DeadReckonUsingSpeedCalculator] Loaded 2023-10-03T21:14:39.207Z,1696367679.207 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-10-03T21:14:39.251Z,1696367679.251 [DeadReckonWithRespectToSeafloor] Loaded 2023-10-03T21:14:39.251Z,1696367679.251 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2023-10-03T21:14:39.265Z,1696367679.265 [NavChart] Loaded 2023-10-03T21:14:39.265Z,1696367679.265 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-10-03T21:14:39.271Z,1696367679.271 [UniversalFixResidualReporter] Loaded 2023-10-03T21:14:39.271Z,1696367679.271 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-10-03T21:14:39.272Z,1696367679.272 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-10-03T21:14:39.273Z,1696367679.273 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-10-03T21:14:39.400Z,1696367679.400 [BuoyancyServo] Loaded 2023-10-03T21:14:39.400Z,1696367679.400 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-10-03T21:14:39.421Z,1696367679.421 [ElevatorServo] Loaded 2023-10-03T21:14:39.421Z,1696367679.421 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-10-03T21:14:39.442Z,1696367679.442 [MassServo] Loaded 2023-10-03T21:14:39.442Z,1696367679.442 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-10-03T21:14:39.461Z,1696367679.461 [RudderServo] Loaded 2023-10-03T21:14:39.462Z,1696367679.462 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-10-03T21:14:39.477Z,1696367679.477 [ThrusterHE] Loaded 2023-10-03T21:14:39.477Z,1696367679.477 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-10-03T21:14:39.477Z,1696367679.477 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-10-03T21:14:39.478Z,1696367679.478 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-10-03T21:14:39.650Z,1696367679.650 [CTD_Seabird] Loaded 2023-10-03T21:14:39.650Z,1696367679.650 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-10-03T21:14:39.651Z,1696367679.651 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9B4E0 2023-10-03T21:14:39.652Z,1696367679.652 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2552 2023-10-03T21:14:39.686Z,1696367679.686 [ESPComponent] Loaded 2023-10-03T21:14:39.686Z,1696367679.686 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-10-03T21:14:39.707Z,1696367679.707 [PAR_Licor] Loaded 2023-10-03T21:14:39.707Z,1696367679.707 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-10-03T21:14:39.749Z,1696367679.749 [WetLabsBB2FL] Loaded 2023-10-03T21:14:39.750Z,1696367679.750 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-10-03T21:14:39.751Z,1696367679.751 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0 2023-10-03T21:14:39.751Z,1696367679.751 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2553 2023-10-03T21:14:39.752Z,1696367679.752 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-10-03T21:14:39.758Z,1696367679.758 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-10-03T21:14:39.761Z,1696367679.761 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-10-03T21:14:39.773Z,1696367679.773 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-10-03T21:14:39.774Z,1696367679.774 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFB4E0 2023-10-03T21:14:39.774Z,1696367679.774 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2554 2023-10-03T21:14:39.779Z,1696367679.779 [Supervisor](INFO): Main Thread ID is 2459 2023-10-03T21:14:39.779Z,1696367679.779 [Supervisor](DEBUG): Running supervisor. 2023-10-03T21:14:39.779Z,1696367679.779 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2555 2023-10-03T21:14:39.780Z,1696367679.780 [CommandExec](INFO): Initializing the command executive. 2023-10-03T21:14:39.782Z,1696367679.782 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2556 2023-10-03T21:14:39.784Z,1696367679.784 [controlThread ThreadHandler](INFO): Handler Thread ID is 2557 2023-10-03T21:14:39.784Z,1696367679.784 [controlThread](DEBUG): Initializing ControlThread 2023-10-03T21:14:39.785Z,1696367679.785 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-10-03T21:14:39.787Z,1696367679.787 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-10-03T21:14:39.788Z,1696367679.788 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-10-03T21:14:39.788Z,1696367679.788 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-10-03T21:14:39.792Z,1696367679.792 [SBIT](INFO): Initialize SBIT Component. 2023-10-03T21:14:39.792Z,1696367679.792 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de 2023-10-03T21:14:39.792Z,1696367679.792 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4 2023-10-03T21:14:39.793Z,1696367679.793 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-10-03T21:14:39.794Z,1696367679.794 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-10-03T21:14:39.795Z,1696367679.795 [SBIT](INFO): Beginning SBIT in 47.000000 seconds. 2023-10-03T21:14:39.795Z,1696367679.795 [IBIT](INFO): Initialize IBIT Component. 2023-10-03T21:14:39.796Z,1696367679.796 [CBIT](DEBUG): Initialize CBIT Component. 2023-10-03T21:14:39.797Z,1696367679.797 [logger ThreadHandler](INFO): Handler Thread ID is 2558 2023-10-03T21:14:39.809Z,1696367679.809 [CBIT](DEBUG): Initialized mux pins. 2023-10-03T21:14:39.810Z,1696367679.810 [CBIT](DEBUG): Initializing the watchdog timer. 2023-10-03T21:14:39.818Z,1696367679.818 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2559 2023-10-03T21:14:39.830Z,1696367679.830 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2560 2023-10-03T21:14:39.831Z,1696367679.831 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-10-03T21:14:39.834Z,1696367679.834 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-10-03T21:14:39.834Z,1696367679.834 [CBIT](DEBUG): Initializing heartbeat. 2023-10-03T21:14:39.842Z,1696367679.842 [Onboard ThreadHandler](INFO): Handler Thread ID is 2561 2023-10-03T21:14:39.859Z,1696367679.859 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2562 2023-10-03T21:14:39.882Z,1696367679.882 [DAT ThreadHandler](INFO): Handler Thread ID is 2563 2023-10-03T21:14:39.883Z,1696367679.883 [DAT](INFO): Powering up 2023-10-03T21:14:39.883Z,1696367679.883 [DAT](DEBUG): Initializing DAT. 2023-10-03T21:14:39.887Z,1696367679.887 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2565 2023-10-03T21:14:39.887Z,1696367679.887 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-10-03T21:14:39.891Z,1696367679.891 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2567 2023-10-03T21:14:39.894Z,1696367679.894 [WetLabsBB2FL](INFO): Powering up 2023-10-03T21:14:39.895Z,1696367679.895 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2569 2023-10-03T21:14:39.898Z,1696367679.898 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2023-10-03T21:14:39.898Z,1696367679.898 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2023-10-03T21:14:39.899Z,1696367679.899 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2023-10-03T21:14:39.899Z,1696367679.899 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2023-10-03T21:14:39.899Z,1696367679.899 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-10-03T21:14:39.899Z,1696367679.899 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-10-03T21:14:39.899Z,1696367679.899 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-10-03T21:14:39.899Z,1696367679.899 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-10-03T21:14:39.900Z,1696367679.900 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2023-10-03T21:14:39.900Z,1696367679.900 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2023-10-03T21:14:39.900Z,1696367679.900 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2023-10-03T21:14:39.900Z,1696367679.900 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2023-10-03T21:14:39.906Z,1696367679.906 [CBIT](DEBUG): Deactivating GF circuits. 2023-10-03T21:14:39.906Z,1696367679.906 [CBIT](DEBUG): Deactivating emergency mode. 2023-10-03T21:14:39.945Z,1696367679.945 [CBIT](DEBUG): Backplane powered. 2023-10-03T21:14:39.946Z,1696367679.946 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-10-03T21:14:39.946Z,1696367679.946 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-10-03T21:14:39.946Z,1696367679.946 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-10-03T21:14:39.947Z,1696367679.947 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-10-03T21:14:39.947Z,1696367679.947 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-10-03T21:14:39.948Z,1696367679.948 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:14:39.949Z,1696367679.949 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-10-03T21:14:39.949Z,1696367679.949 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-10-03T21:14:39.954Z,1696367679.954 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-10-03T21:14:39.976Z,1696367679.976 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-03T21:14:40.008Z,1696367680.008 [MissionManager](DEBUG): 2023-10-03T21:14:40.009Z,1696367680.009 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-10-03T21:14:40.069Z,1696367680.069 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-10-03T21:14:40.070Z,1696367680.070 [Default:A.Wait](DEBUG): Construct Wait. 2023-10-03T21:14:40.073Z,1696367680.073 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-10-03T21:14:40.101Z,1696367680.101 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-10-03T21:14:40.104Z,1696367680.104 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-10-03T21:14:40.151Z,1696367680.151 [Default:E.Execute](DEBUG): Construct Execute. 2023-10-03T21:14:40.166Z,1696367680.166 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-10-03T21:14:40.171Z,1696367680.171 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-10-03T21:14:40.182Z,1696367680.182 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-10-03T21:14:40.251Z,1696367680.251 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2023-10-03T21:14:40.252Z,1696367680.252 [Power24vConverter](INFO): Powering up. 2023-10-03T21:14:40.262Z,1696367680.262 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-10-03T21:14:40.274Z,1696367680.274 [Radio_Surface](INFO): Powering up 2023-10-03T21:14:40.296Z,1696367680.296 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-10-03T21:14:40.302Z,1696367680.302 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-10-03T21:14:40.303Z,1696367680.303 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-10-03T21:14:40.310Z,1696367680.310 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-10-03T21:14:40.311Z,1696367680.311 [MassServo](DEBUG): Initializing EZServoServo. 2023-10-03T21:14:40.318Z,1696367680.318 [MassServo](DEBUG): Initializing MassServo. 2023-10-03T21:14:40.319Z,1696367680.319 [RudderServo](DEBUG): Initializing EZServoServo. 2023-10-03T21:14:40.326Z,1696367680.326 [RudderServo](DEBUG): Initializing RudderServo. 2023-10-03T21:14:40.327Z,1696367680.327 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-10-03T21:14:40.334Z,1696367680.334 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-10-03T21:14:42.002Z,1696367682.002 [WetLabsBB2FL](INFO): Powering down 2023-10-03T21:14:46.086Z,1696367686.086 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-10-03T21:14:49.482Z,1696367689.482 [DAT](INFO): DAT read: 2023-10-03T21:14:49.484Z,1696367689.484 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-10-03T21:14:50.743Z,1696367690.743 [DAT](INFO): DAT read: MF Frequency Band 2023-10-03T21:14:50.744Z,1696367690.744 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.10.9 2023-10-03T21:14:50.744Z,1696367690.744 [DAT](INFO): DAT read: Oct 3 2023 21:13:23 2023-10-03T21:14:51.751Z,1696367691.751 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-10-03T21:14:51.752Z,1696367691.752 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-10-03T21:14:51.752Z,1696367691.752 [DAT](INFO): commRate: 800 2023-10-03T21:14:53.818Z,1696367693.818 [DAT](INFO): entering command mode 2023-10-03T21:14:54.018Z,1696367694.018 [DAT](INFO): DAT read: 2023-10-03T21:14:54.019Z,1696367694.019 [DAT](INFO): DAT read: user:1> 2023-10-03T21:14:54.019Z,1696367694.019 [DAT](INFO): setting verbose to 3 2023-10-03T21:14:54.270Z,1696367694.270 [DAT](INFO): DAT read: user:1> 2023-10-03T21:14:54.271Z,1696367694.271 [DAT](INFO): DAT read: Verbose | 3 2023-10-03T21:14:54.272Z,1696367694.272 [DAT](INFO): set verbose to 3 2023-10-03T21:14:54.272Z,1696367694.272 [DAT](INFO): setting DatVerbose to 27440 2023-10-03T21:14:54.522Z,1696367694.522 [DAT](INFO): DAT read: user:2> 2023-10-03T21:14:54.523Z,1696367694.523 [DAT](INFO): DAT read: DatVerbose | 27440 2023-10-03T21:14:54.524Z,1696367694.524 [DAT](INFO): set DatVerbose to 27440 2023-10-03T21:14:54.524Z,1696367694.524 [DAT](INFO): setting transmit power to 8 2023-10-03T21:14:54.775Z,1696367694.775 [DAT](INFO): DAT read: user:3> 2023-10-03T21:14:54.776Z,1696367694.776 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-10-03T21:14:54.776Z,1696367694.776 [DAT](INFO): set transmit power to 8 2023-10-03T21:14:54.777Z,1696367694.777 [DAT](INFO): setting local address to 1 2023-10-03T21:14:55.026Z,1696367695.026 [DAT](INFO): DAT read: user:4> 2023-10-03T21:14:55.027Z,1696367695.027 [DAT](INFO): DAT read: LocalAddr | 1 2023-10-03T21:14:55.028Z,1696367695.028 [DAT](INFO): set local address to 1 2023-10-03T21:14:55.029Z,1696367695.029 [DAT](INFO): Setting time to: 21:14:55 And date to:10/3/2023 2023-10-03T21:14:55.279Z,1696367695.279 [DAT](INFO): DAT read: user:5> 2023-10-03T21:14:55.280Z,1696367695.280 [DAT](INFO): DAT read: Tue Oct 3, 2023 21:14:55 2023-10-03T21:14:55.280Z,1696367695.280 [DAT](INFO): Local DAT time set to Tue Oct 3, 2023 21:14:55 2023-10-03T21:14:55.280Z,1696367695.280 [DAT](INFO): DAT read: Ok 2023-10-03T21:14:55.281Z,1696367695.281 [DAT](INFO): unknown deviceResponse_: Ok 2023-10-03T21:15:08.063Z,1696367708.063 [NAL9602](INFO): Powering up NAL9602 2023-10-03T21:15:14.626Z,1696367714.626 [Waterlinked](INFO): Powering down 2023-10-03T21:15:18.460Z,1696367718.460 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005132 2023-10-03T21:15:18.971Z,1696367718.971 [NAL9602](INFO): NAL9602 initialized 2023-10-03T21:15:22.690Z,1696367722.690 [CommandExec](IMPORTANT): got command failComponent 2023-10-03T21:15:22.690Z,1696367722.690 [CommandExec](IMPORTANT): Failed components: 2023-10-03T21:15:22.691Z,1696367722.691 [CommandExec](IMPORTANT): No failed Components. 2023-10-03T21:15:27.492Z,1696367727.492 [SBIT](IMPORTANT): Beginning Startup BIT 2023-10-03T21:15:27.496Z,1696367727.496 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-03T21:15:38.542Z,1696367738.542 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002781 CHAN A1 (24V): 0.003255 CHAN A2 (12V): -0.000476 CHAN A3 (5V): -0.003525 CHAN B0 (3.3V): -0.000502 CHAN B1 (3.15aV): -0.000661 CHAN B2 (3.15bV): -0.000405 CHAN B3 (GND): -0.001604 OPEN: 0.006851 Full Scale: +/- 1 mA 2023-10-03T21:15:50.280Z,1696367750.280 [CommandExec](IMPORTANT): got command ! /etc/rc.d/init.d/settime restart 2023-10-03T21:15:46.172Z,1696367746.172 [CommandExec](IMPORTANT): settime stop or restart Setting hardware clock from system clock Tue Oct 3 21:15:50 2023 0.000000 seconds settime start or restart Setting clock frequency and tick values from nonvolatile memory. mode: 16386 -o offset: 0 -f frequency: 0 maxerror: 16000000 esterror: 16000000 status: 64 (UNSYNC) -p timeconstant: 2 precision: 1 tolerance: 32768000 -t tick: 10000 time.tv_sec: 1696367750 time.tv_usec: 559055 return value: 5 (clock not synchronized) ping returned from NTP server: 216.239.35.4 45200 76551.846 45708.0 0.0 -6691734.7 76.3 0 Time set from NTP server: 216.239.35.4 Setting hardware clock from system clock Tue Oct 3 21:15:46 2023 0.000000 seconds 2023-10-03T21:16:21.205Z,1696367781.205 [SBIT](IMPORTANT): SBIT PASSED 2023-10-03T21:16:21.205Z,1696367781.205 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-10-03T21:16:21.206Z,1696367781.206 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2023-10-03T21:16:21.206Z,1696367781.206 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool; 2023-10-03T21:16:21.207Z,1696367781.207 [SBIT](IMPORTANT): CBIT.gf24Offset=158.5 microampere; 2023-10-03T21:16:21.207Z,1696367781.207 [SBIT](IMPORTANT): PowerOnly.sampleTime1=1 second; 2023-10-03T21:16:21.207Z,1696367781.207 [SBIT](IMPORTANT): Rowe_600.loadAtStartup=0 bool; 2023-10-03T21:16:21.207Z,1696367781.207 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=300.106325 cubic_centimeter; 2023-10-03T21:16:21.207Z,1696367781.207 [SBIT](IMPORTANT): VerticalControl.massDefault=6.776897 millimeter; 2023-10-03T21:16:21.616Z,1696367781.616 [MissionManager](IMPORTANT): Started mission Startup 2023-10-03T21:16:21.616Z,1696367781.616 [Startup] Running Loop=1 2023-10-03T21:16:21.616Z,1696367781.616 [Startup](DEBUG): Aggregate::initialize Startup 2023-10-03T21:16:21.617Z,1696367781.617 [Startup:A.GoToSurface] Running Loop=1 2023-10-03T21:16:21.617Z,1696367781.617 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-03T21:16:21.617Z,1696367781.617 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-03T21:16:21.618Z,1696367781.618 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-03T21:16:21.618Z,1696367781.618 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-03T21:16:21.618Z,1696367781.618 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-03T21:16:21.619Z,1696367781.619 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-03T21:16:21.621Z,1696367781.621 [Startup:StartupSatComms] Running Loop=1 2023-10-03T21:16:21.621Z,1696367781.621 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-10-03T21:16:21.621Z,1696367781.621 [Startup:StartupSatComms:A] Running Loop=1 2023-10-03T21:16:22.030Z,1696367782.030 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-10-03T21:17:15.956Z,1696367835.956 [CommandExec](IMPORTANT): got command failComponent 2023-10-03T21:17:15.956Z,1696367835.956 [CommandExec](IMPORTANT): Failed components: 2023-10-03T21:17:15.956Z,1696367835.956 [CommandExec](IMPORTANT): No failed Components. 2023-10-03T21:17:21.788Z,1696367841.788 [CommandExec](IMPORTANT): got command gfscan 2023-10-03T21:17:21.819Z,1696367841.819 [Startup:StartupSatComms:A](INFO): Timed out from 2023-10-03T21:16:21.6Z 2023-10-03T21:17:21.819Z,1696367841.819 [Startup:StartupSatComms:A] Stopped 2023-10-03T21:17:21.819Z,1696367841.819 [Startup:StartupSatComms:B] Running Loop=1 2023-10-03T21:17:21.829Z,1696367841.829 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-03T21:17:22.219Z,1696367842.219 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-03T21:17:28.907Z,1696367848.907 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231003T200612/Courier0013.lzma 2023-10-03T21:17:29.909Z,1696367849.909 [DataOverHttps](INFO): Moved sent file to Logs/20231003T200612/Courier0013.lzma.bak 2023-10-03T21:17:29.909Z,1696367849.909 [DataOverHttps](INFO): SBD MOMSN=18979355 2023-10-03T21:17:32.740Z,1696367852.740 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002775 CHAN A1 (24V): 0.002729 CHAN A2 (12V): -0.000123 CHAN A3 (5V): -0.003828 CHAN B0 (3.3V): -0.000330 CHAN B1 (3.15aV): -0.000434 CHAN B2 (3.15bV): 0.000146 CHAN B3 (GND): -0.001619 OPEN: 0.007098 Full Scale: +/- 1 mA 2023-10-03T21:17:36.160Z,1696367856.160 [CommandExec](IMPORTANT): got command failComponent 2023-10-03T21:17:36.160Z,1696367856.160 [CommandExec](IMPORTANT): Failed components: 2023-10-03T21:17:36.160Z,1696367856.160 [CommandExec](IMPORTANT): No failed Components. 2023-10-03T21:17:39.972Z,1696367859.972 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-10-03T21:17:39.972Z,1696367859.972 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T21:17:39.973Z,1696367859.973 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-10-03T21:17:39.973Z,1696367859.973 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T21:17:39.975Z,1696367859.975 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T21:17:39.975Z,1696367859.975 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:17:39.986Z,1696367859.986 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T21:17:39.986Z,1696367859.986 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T21:17:39.986Z,1696367859.986 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:17:40.387Z,1696367860.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T21:17:40.387Z,1696367860.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-10-03T21:17:40.387Z,1696367860.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T21:17:40.388Z,1696367860.388 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-10-03T21:17:40.388Z,1696367860.388 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:17:40.388Z,1696367860.388 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T21:17:40.781Z,1696367860.781 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:17:45.647Z,1696367865.647 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231003T211430/Courier0000.lzma 2023-10-03T21:17:46.649Z,1696367866.649 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0000.lzma.bak 2023-10-03T21:17:46.649Z,1696367866.649 [DataOverHttps](INFO): SBD MOMSN=18979357 2023-10-03T21:18:02.128Z,1696367882.128 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20231003T200612/Express0014.lzma 2023-10-03T21:18:03.129Z,1696367883.129 [DataOverHttps](INFO): Moved sent file to Logs/20231003T200612/Express0014.lzma.bak 2023-10-03T21:18:03.129Z,1696367883.129 [DataOverHttps](INFO): SBD MOMSN=18979360 2023-10-03T21:18:18.586Z,1696367898.586 [DataOverHttps](INFO): Sending 1216 bytes from file Logs/20231003T211430/Express0001.lzma 2023-10-03T21:18:19.585Z,1696367899.585 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0001.lzma.bak 2023-10-03T21:18:19.585Z,1696367899.585 [DataOverHttps](INFO): SBD MOMSN=18979366 2023-10-03T21:18:21.618Z,1696367901.618 [Startup:StartupSatComms:B] Stopped 2023-10-03T21:18:21.618Z,1696367901.618 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-10-03T21:18:21.618Z,1696367901.618 [Startup:StartupSatComms] Stopped 2023-10-03T21:18:21.619Z,1696367901.619 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-10-03T21:18:21.624Z,1696367901.624 [Startup](INFO): Completed Startup 2023-10-03T21:18:21.624Z,1696367901.624 [MissionManager](INFO): Startup is completed. 2023-10-03T21:18:21.624Z,1696367901.624 [MissionManager](INFO): Uninitializing Mission Startup 2023-10-03T21:18:21.624Z,1696367901.624 [Startup] Stopped 2023-10-03T21:18:21.624Z,1696367901.624 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-10-03T21:18:21.624Z,1696367901.624 [Startup:A.GoToSurface] Stopped 2023-10-03T21:18:21.624Z,1696367901.624 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-03T21:18:21.991Z,1696367901.991 [MissionManager](IMPORTANT): Started mission Default 2023-10-03T21:18:21.992Z,1696367901.992 [Default] Running Loop=1 2023-10-03T21:18:21.992Z,1696367901.992 [Default](DEBUG): Aggregate::initialize Default 2023-10-03T21:18:21.992Z,1696367901.992 [Default:B.GoToSurface] Running Loop=1 2023-10-03T21:18:21.992Z,1696367901.992 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-10-03T21:18:21.992Z,1696367901.992 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-10-03T21:18:21.993Z,1696367901.993 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-10-03T21:18:21.993Z,1696367901.993 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-10-03T21:18:21.993Z,1696367901.993 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-10-03T21:18:21.994Z,1696367901.994 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-10-03T21:18:21.994Z,1696367901.994 [Default:A.Wait] Running Loop=1 2023-10-03T21:18:21.994Z,1696367901.994 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-10-03T21:18:35.348Z,1696367915.348 [Default:A.Wait](INFO): Done Waiting. 2023-10-03T21:18:35.348Z,1696367915.348 [Default:A.Wait] Stopped 2023-10-03T21:18:35.348Z,1696367915.348 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T21:18:35.749Z,1696367915.749 [Default:CheckIn] Running Loop=1 2023-10-03T21:18:35.749Z,1696367915.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T21:18:35.749Z,1696367915.749 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T21:18:36.131Z,1696367916.131 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-10-03T21:19:54.548Z,1696367994.548 [CommandExec](IMPORTANT): got command failComponent 2023-10-03T21:19:54.548Z,1696367994.548 [CommandExec](IMPORTANT): Failed components: 2023-10-03T21:19:54.548Z,1696367994.548 [CommandExec](IMPORTANT): No failed Components. 2023-10-03T21:20:22.036Z,1696368022.036 [CommandExec](IMPORTANT): got command burn on 2023-10-03T21:20:22.036Z,1696368022.036 [CommandExec](IMPORTANT): Activating dropweight wire 2023-10-03T21:20:22.370Z,1696368022.370 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-03T21:20:32.869Z,1696368032.869 [CommandExec](IMPORTANT): got command burn off 2023-10-03T21:20:32.869Z,1696368032.869 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-10-03T21:20:40.964Z,1696368040.964 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-10-03T21:20:40.964Z,1696368040.964 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T21:20:40.965Z,1696368040.965 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-10-03T21:20:40.965Z,1696368040.965 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T21:20:40.966Z,1696368040.966 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T21:20:40.966Z,1696368040.966 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:20:41.021Z,1696368041.021 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T21:20:41.021Z,1696368041.021 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T21:20:41.021Z,1696368041.021 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:20:41.378Z,1696368041.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T21:20:41.378Z,1696368041.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-10-03T21:20:41.379Z,1696368041.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T21:20:41.379Z,1696368041.379 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-10-03T21:20:41.380Z,1696368041.380 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:20:41.380Z,1696368041.380 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T21:20:41.772Z,1696368041.772 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:22:33.232Z,1696368153.232 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-10-03T21:23:35.927Z,1696368215.927 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-03T21:18:35.7Z 2023-10-03T21:23:35.928Z,1696368215.928 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T21:23:35.928Z,1696368215.928 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T21:23:36.298Z,1696368216.298 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-10-03T21:23:41.708Z,1696368221.708 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231003T211430/Courier0004.lzma 2023-10-03T21:23:41.956Z,1696368221.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-10-03T21:23:41.956Z,1696368221.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T21:23:41.957Z,1696368221.957 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-10-03T21:23:41.957Z,1696368221.957 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T21:23:41.959Z,1696368221.959 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2023-10-03T21:23:41.959Z,1696368221.959 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:23:41.970Z,1696368221.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T21:23:41.970Z,1696368221.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T21:23:41.970Z,1696368221.970 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:23:42.419Z,1696368222.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T21:23:42.419Z,1696368222.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-10-03T21:23:42.420Z,1696368222.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T21:23:42.420Z,1696368222.420 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-10-03T21:23:42.421Z,1696368222.421 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:23:42.421Z,1696368222.421 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2023-10-03T21:23:42.661Z,1696368222.661 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0004.lzma.bak 2023-10-03T21:23:42.661Z,1696368222.661 [DataOverHttps](INFO): SBD MOMSN=18979394 2023-10-03T21:23:42.775Z,1696368222.775 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:23:58.381Z,1696368238.381 [DataOverHttps](INFO): Sending 467 bytes from file Logs/20231003T211430/Express0005.lzma 2023-10-03T21:23:59.381Z,1696368239.381 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0005.lzma.bak 2023-10-03T21:23:59.381Z,1696368239.381 [DataOverHttps](INFO): SBD MOMSN=18979396 2023-10-03T21:24:01.370Z,1696368241.370 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T21:24:01.370Z,1696368241.370 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T21:24:01.371Z,1696368241.371 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T21:25:21.333Z,1696368321.333 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-03T21:25:21.333Z,1696368321.333 [NAL9602] Data Fault, FailCount= 1 2023-10-03T21:25:21.333Z,1696368321.333 [NAL9602](ERROR): Data Fault 2023-10-03T21:25:21.352Z,1696368321.352 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-03T21:25:21.741Z,1696368321.741 [NAL9602](INFO): Powering down 2023-10-03T21:25:22.563Z,1696368322.563 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-03T21:25:22.563Z,1696368322.563 [NAL9602] No Fault, FailCount= 1 2023-10-03T21:25:52.037Z,1696368352.037 [NAL9602](INFO): Powering up NAL9602 2023-10-03T21:26:02.949Z,1696368362.949 [NAL9602](INFO): NAL9602 initialized 2023-10-03T21:26:42.948Z,1696368402.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-10-03T21:26:42.949Z,1696368402.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T21:26:42.950Z,1696368402.950 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-10-03T21:26:42.950Z,1696368402.950 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T21:26:42.951Z,1696368402.951 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2023-10-03T21:26:42.951Z,1696368402.951 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:26:42.962Z,1696368402.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T21:26:42.962Z,1696368402.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T21:26:42.962Z,1696368402.962 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:26:43.362Z,1696368403.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T21:26:43.362Z,1696368403.362 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-10-03T21:26:43.362Z,1696368403.362 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T21:26:43.362Z,1696368403.362 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-10-03T21:26:43.363Z,1696368403.363 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:26:43.363Z,1696368403.363 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2023-10-03T21:26:43.784Z,1696368403.784 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:28:25.633Z,1696368505.633 [CommandExec](IMPORTANT): got command strobe off 2023-10-03T21:28:25.633Z,1696368505.633 [CommandExec](IMPORTANT): Deactivating strobe 2023-10-03T21:29:01.931Z,1696368541.931 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T21:29:01.931Z,1696368541.931 [Default:CheckIn:C.Wait] Stopped 2023-10-03T21:29:01.931Z,1696368541.931 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T21:29:01.931Z,1696368541.931 [Default:CheckIn:D] Running Loop=1 2023-10-03T21:29:02.342Z,1696368542.342 [Default:CheckIn:D] Stopped 2023-10-03T21:29:02.342Z,1696368542.342 [Default:CheckIn:E] Running Loop=1 2023-10-03T21:29:02.740Z,1696368542.740 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672497 min 2023-10-03T21:29:02.740Z,1696368542.740 [Default:CheckIn:E] Stopped 2023-10-03T21:29:02.740Z,1696368542.740 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T21:29:02.740Z,1696368542.740 [Default:CheckIn] Stopped 2023-10-03T21:29:02.740Z,1696368542.740 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T21:29:02.740Z,1696368542.740 [Default:CheckIn](INFO): Running loop #2 2023-10-03T21:29:02.741Z,1696368542.741 [Default:CheckIn] Running Loop=2 2023-10-03T21:29:02.741Z,1696368542.741 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T21:29:02.741Z,1696368542.741 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T21:29:43.973Z,1696368583.973 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-10-03T21:29:43.973Z,1696368583.973 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T21:29:43.974Z,1696368583.974 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-10-03T21:29:43.975Z,1696368583.975 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T21:29:43.976Z,1696368583.976 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2023-10-03T21:29:43.976Z,1696368583.976 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:29:43.987Z,1696368583.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T21:29:43.987Z,1696368583.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T21:29:43.987Z,1696368583.987 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:29:44.358Z,1696368584.358 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T21:29:44.358Z,1696368584.358 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-10-03T21:29:44.358Z,1696368584.358 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T21:29:44.358Z,1696368584.358 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-10-03T21:29:44.359Z,1696368584.359 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:29:44.359Z,1696368584.359 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2023-10-03T21:29:44.776Z,1696368584.776 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:31:25.395Z,1696368685.395 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2023-10-03T21:32:44.964Z,1696368764.964 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-10-03T21:32:44.965Z,1696368764.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T21:32:44.966Z,1696368764.966 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2023-10-03T21:32:44.966Z,1696368764.966 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T21:32:44.967Z,1696368764.967 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6 2023-10-03T21:32:44.967Z,1696368764.967 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:32:45.002Z,1696368765.002 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T21:32:45.003Z,1696368765.003 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T21:32:45.003Z,1696368765.003 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:32:45.382Z,1696368765.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T21:32:45.382Z,1696368765.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-10-03T21:32:45.383Z,1696368765.383 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T21:32:45.383Z,1696368765.383 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2023-10-03T21:32:45.384Z,1696368765.384 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:32:45.384Z,1696368765.384 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6 2023-10-03T21:32:45.763Z,1696368765.763 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:34:02.910Z,1696368842.910 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-03T21:29:02.7Z 2023-10-03T21:34:02.911Z,1696368842.911 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T21:34:02.911Z,1696368842.911 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T21:34:10.283Z,1696368850.283 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231003T211430/Courier0007.lzma 2023-10-03T21:34:11.285Z,1696368851.285 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0007.lzma.bak 2023-10-03T21:34:11.285Z,1696368851.285 [DataOverHttps](INFO): SBD MOMSN=18979419 2023-10-03T21:34:26.843Z,1696368866.843 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20231003T211430/Express0008.lzma 2023-10-03T21:34:27.846Z,1696368867.846 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0008.lzma.bak 2023-10-03T21:34:27.846Z,1696368867.846 [DataOverHttps](INFO): SBD MOMSN=18979421 2023-10-03T21:34:29.994Z,1696368869.994 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T21:34:29.994Z,1696368869.994 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T21:34:29.994Z,1696368869.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T21:35:45.945Z,1696368945.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-10-03T21:35:45.945Z,1696368945.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-10-03T21:35:45.946Z,1696368945.946 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2023-10-03T21:35:45.946Z,1696368945.946 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-10-03T21:35:45.947Z,1696368945.947 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7 2023-10-03T21:35:45.947Z,1696368945.947 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:35:45.986Z,1696368945.986 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-10-03T21:35:45.987Z,1696368945.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-10-03T21:35:45.987Z,1696368945.987 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:35:46.386Z,1696368946.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-10-03T21:35:46.386Z,1696368946.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-10-03T21:35:46.386Z,1696368946.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-10-03T21:35:46.386Z,1696368946.386 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2023-10-03T21:35:46.387Z,1696368946.387 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:35:46.387Z,1696368946.387 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7 2023-10-03T21:35:46.772Z,1696368946.772 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-10-03T21:36:04.517Z,1696368964.517 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-10-03T21:36:04.517Z,1696368964.517 [NAL9602] Data Fault, FailCount= 2 2023-10-03T21:36:04.517Z,1696368964.517 [NAL9602](ERROR): Data Fault 2023-10-03T21:36:04.558Z,1696368964.558 [CBIT](ERROR): Data Fault in component: NAL9602 2023-10-03T21:36:04.921Z,1696368964.921 [NAL9602](INFO): Powering down 2023-10-03T21:36:05.751Z,1696368965.751 [CBIT](INFO): Clearing failed state for component NAL9602 2023-10-03T21:36:05.751Z,1696368965.751 [NAL9602] No Fault, FailCount= 2 2023-10-03T21:36:35.221Z,1696368995.221 [NAL9602](INFO): Powering up NAL9602 2023-10-03T21:36:46.129Z,1696369006.129 [NAL9602](INFO): NAL9602 initialized 2023-10-03T21:37:47.605Z,1696369067.605 [DataOverHttps](IMPORTANT): SBD MTMSN=20231003T213746 2023-10-03T21:37:54.848Z,1696369074.848 [DataOverHttps](INFO): Received command: ibit 2023-10-03T21:37:54.952Z,1696369074.952 [CommandExec](IMPORTANT): got command ibit 2023-10-03T21:37:55.253Z,1696369075.253 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-10-03T21:37:55.253Z,1696369075.253 [IBIT](IMPORTANT): Beginning control surface checks. 2023-10-03T21:37:55.256Z,1696369075.256 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-03T21:38:06.210Z,1696369086.210 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000072 CHAN A1 (24V): 0.000274 CHAN A2 (12V): -0.000055 CHAN A3 (5V): -0.002143 CHAN B0 (3.3V): 0.000297 CHAN B1 (3.15aV): 0.000602 CHAN B2 (3.15bV): 0.000154 CHAN B3 (GND): -0.000998 OPEN: 0.007016 Full Scale: +/- 1 mA 2023-10-03T21:38:33.595Z,1696369113.595 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213831.00,A,3648.14245,N,12147.24974,W,1.516,0.00,031023,,,A*74 2023-10-03T21:38:33.598Z,1696369113.598 [NAL9602](INFO): GPS fix at 20231003T213831: (36.802374, -121.787496) 2023-10-03T21:38:36.828Z,1696369116.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213835.00,A,3648.14164,N,12147.24608,W,1.924,101.62,031023,,,A*7D 2023-10-03T21:38:36.830Z,1696369116.830 [NAL9602](INFO): GPS fix at 20231003T213835: (36.802361, -121.787435) 2023-10-03T21:38:39.662Z,1696369119.662 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213838.00,A,3648.14144,N,12147.24396,W,2.060,104.54,031023,,,A*7A 2023-10-03T21:38:39.664Z,1696369119.664 [NAL9602](INFO): GPS fix at 20231003T213838: (36.802357, -121.787399) 2023-10-03T21:38:42.902Z,1696369122.902 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213841.00,A,3648.14077,N,12147.24209,W,1.302,104.54,031023,,,A*76 2023-10-03T21:38:42.905Z,1696369122.905 [NAL9602](INFO): GPS fix at 20231003T213841: (36.802346, -121.787368) 2023-10-03T21:38:45.715Z,1696369125.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213844.00,A,3648.14043,N,12147.23982,W,2.060,104.54,031023,,,A*7F 2023-10-03T21:38:45.719Z,1696369125.719 [NAL9602](INFO): GPS fix at 20231003T213844: (36.802340, -121.787330) 2023-10-03T21:38:46.962Z,1696369126.962 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T21:38:46.962Z,1696369126.962 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:38:46.977Z,1696369126.977 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:38:47.384Z,1696369127.384 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:38:47.384Z,1696369127.384 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T21:38:48.952Z,1696369128.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213847.00,A,3648.13998,N,12147.23832,W,1.633,104.54,031023,,,A*7D 2023-10-03T21:38:48.954Z,1696369128.954 [NAL9602](INFO): GPS fix at 20231003T213847: (36.802333, -121.787305) 2023-10-03T21:38:51.817Z,1696369131.817 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213850.00,A,3648.13970,N,12147.23704,W,1.166,104.54,031023,,,A*70 2023-10-03T21:38:51.819Z,1696369131.819 [NAL9602](INFO): GPS fix at 20231003T213850: (36.802328, -121.787284) 2023-10-03T21:38:54.638Z,1696369134.638 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213852.00,A,3648.13897,N,12147.23631,W,1.536,104.54,031023,,,A*7C 2023-10-03T21:38:54.641Z,1696369134.641 [NAL9602](INFO): GPS fix at 20231003T213852: (36.802316, -121.787272) 2023-10-03T21:38:57.878Z,1696369137.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213856.00,A,3648.14041,N,12147.23441,W,1.400,104.54,031023,,,A*7D 2023-10-03T21:38:57.881Z,1696369137.881 [NAL9602](INFO): GPS fix at 20231003T213856: (36.802340, -121.787240) 2023-10-03T21:39:00.724Z,1696369140.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213859.00,A,3648.14045,N,12147.23346,W,0.058,104.54,031023,,,A*7E 2023-10-03T21:39:00.726Z,1696369140.726 [NAL9602](INFO): GPS fix at 20231003T213859: (36.802341, -121.787224) 2023-10-03T21:39:03.942Z,1696369143.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213902.00,A,3648.14068,N,12147.23316,W,0.058,104.54,031023,,,A*7B 2023-10-03T21:39:03.945Z,1696369143.945 [NAL9602](INFO): GPS fix at 20231003T213902: (36.802345, -121.787219) 2023-10-03T21:39:06.766Z,1696369146.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213905.00,A,3648.14084,N,12147.23285,W,0.253,104.54,031023,,,A*7C 2023-10-03T21:39:06.778Z,1696369146.778 [NAL9602](INFO): GPS fix at 20231003T213905: (36.802347, -121.787214) 2023-10-03T21:39:09.607Z,1696369149.607 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213907.00,A,3648.14101,N,12147.23258,W,0.097,104.54,031023,,,A*78 2023-10-03T21:39:09.609Z,1696369149.609 [NAL9602](INFO): GPS fix at 20231003T213907: (36.802350, -121.787210) 2023-10-03T21:39:12.891Z,1696369152.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213911.00,A,3648.14109,N,12147.23213,W,0.136,104.54,031023,,,A*72 2023-10-03T21:39:12.893Z,1696369152.893 [NAL9602](INFO): GPS fix at 20231003T213911: (36.802352, -121.787202) 2023-10-03T21:39:15.658Z,1696369155.658 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213914.00,A,3648.14109,N,12147.23194,W,0.214,104.54,031023,,,A*78 2023-10-03T21:39:15.661Z,1696369155.661 [NAL9602](INFO): GPS fix at 20231003T213914: (36.802352, -121.787199) 2023-10-03T21:39:18.890Z,1696369158.890 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213917.00,A,3648.14127,N,12147.23184,W,0.253,104.54,031023,,,A*75 2023-10-03T21:39:18.893Z,1696369158.893 [NAL9602](INFO): GPS fix at 20231003T213917: (36.802354, -121.787197) 2023-10-03T21:39:21.817Z,1696369161.817 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213920.00,A,3648.14132,N,12147.23181,W,0.233,104.54,031023,,,A*76 2023-10-03T21:39:21.820Z,1696369161.820 [NAL9602](INFO): GPS fix at 20231003T213920: (36.802355, -121.787197) 2023-10-03T21:39:24.950Z,1696369164.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213923.00,A,3648.14125,N,12147.23067,W,1.263,89.89,031023,,,A*4A 2023-10-03T21:39:24.960Z,1696369164.960 [NAL9602](INFO): GPS fix at 20231003T213923: (36.802354, -121.787178) 2023-10-03T21:39:27.798Z,1696369167.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213926.00,A,3648.14142,N,12147.22935,W,2.371,101.92,031023,,,A*7B 2023-10-03T21:39:27.801Z,1696369167.801 [NAL9602](INFO): GPS fix at 20231003T213926: (36.802357, -121.787156) 2023-10-03T21:39:30.606Z,1696369170.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213928.00,A,3648.14189,N,12147.22851,W,1.283,88.33,031023,,,A*45 2023-10-03T21:39:30.609Z,1696369170.609 [NAL9602](INFO): GPS fix at 20231003T213928: (36.802365, -121.787142) 2023-10-03T21:39:33.839Z,1696369173.839 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213932.00,A,3648.14299,N,12147.22727,W,0.816,48.56,031023,,,A*4A 2023-10-03T21:39:33.841Z,1696369173.841 [NAL9602](INFO): GPS fix at 20231003T213932: (36.802383, -121.787121) 2023-10-03T21:39:36.662Z,1696369176.662 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213935.00,A,3648.14324,N,12147.22682,W,0.389,48.56,031023,,,A*49 2023-10-03T21:39:36.674Z,1696369176.674 [NAL9602](INFO): GPS fix at 20231003T213935: (36.802387, -121.787114) 2023-10-03T21:39:39.903Z,1696369179.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213938.00,A,3648.14359,N,12147.22696,W,0.564,48.56,031023,,,A*4E 2023-10-03T21:39:39.905Z,1696369179.905 [NAL9602](INFO): GPS fix at 20231003T213938: (36.802393, -121.787116) 2023-10-03T21:39:42.723Z,1696369182.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213941.00,A,3648.14416,N,12147.22667,W,0.972,48.56,031023,,,A*49 2023-10-03T21:39:42.726Z,1696369182.726 [NAL9602](INFO): GPS fix at 20231003T213941: (36.802403, -121.787111) 2023-10-03T21:39:45.955Z,1696369185.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213944.00,A,3648.14476,N,12147.22616,W,0.700,48.56,031023,,,A*47 2023-10-03T21:39:45.957Z,1696369185.957 [NAL9602](INFO): GPS fix at 20231003T213944: (36.802413, -121.787103) 2023-10-03T21:39:46.408Z,1696369186.408 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2023-10-03T21:39:46.802Z,1696369186.802 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2023-10-03T21:39:47.187Z,1696369187.187 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-10-03T21:39:47.187Z,1696369187.187 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-10-03T21:39:47.188Z,1696369187.188 [IBIT](IMPORTANT): Pressure:10.094740 PSI 2023-10-03T21:39:47.188Z,1696369187.188 [IBIT](IMPORTANT): Humidity:17.598583 % 2023-10-03T21:39:47.598Z,1696369187.598 [IBIT](IMPORTANT): Vehicle Pitch:-0.730901 degrees 2023-10-03T21:39:47.599Z,1696369187.599 [IBIT](IMPORTANT): Vehicle Roll:-2.593809 degrees 2023-10-03T21:39:47.599Z,1696369187.599 [IBIT](IMPORTANT): Vehicle Heading:31.321049 degrees 2023-10-03T21:39:48.055Z,1696369188.055 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-10-03T21:39:48.055Z,1696369188.055 [IBIT](IMPORTANT): buoyancyNeutral: 300.106323 cc 2023-10-03T21:39:48.055Z,1696369188.055 [IBIT](IMPORTANT): massDefault: 0.677690 cm 2023-10-03T21:39:48.056Z,1696369188.056 [IBIT](IMPORTANT): stopDepth: 112.000000 m 2023-10-03T21:39:48.056Z,1696369188.056 [IBIT](IMPORTANT): abortDepth: 130.000000 m 2023-10-03T21:39:48.056Z,1696369188.056 [IBIT](IMPORTANT): IBIT FAILED 2023-10-03T21:39:48.424Z,1696369188.424 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T21:39:48.424Z,1696369188.424 [Default:CheckIn:C.Wait] Stopped 2023-10-03T21:39:48.424Z,1696369188.424 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T21:39:48.424Z,1696369188.424 [Default:CheckIn:D] Running Loop=1 2023-10-03T21:39:48.799Z,1696369188.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213947.00,A,3648.14515,N,12147.22577,W,0.661,48.56,031023,,,A*42 2023-10-03T21:39:48.801Z,1696369188.801 [NAL9602](INFO): GPS fix at 20231003T213947: (36.802419, -121.787096) 2023-10-03T21:39:48.834Z,1696369188.834 [Default:CheckIn:D] Stopped 2023-10-03T21:39:48.835Z,1696369188.835 [Default:CheckIn:E] Running Loop=1 2023-10-03T21:39:49.253Z,1696369189.253 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.447378 min 2023-10-03T21:39:49.253Z,1696369189.253 [Default:CheckIn:E] Stopped 2023-10-03T21:39:49.254Z,1696369189.254 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T21:39:49.254Z,1696369189.254 [Default:CheckIn] Stopped 2023-10-03T21:39:49.254Z,1696369189.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T21:39:49.254Z,1696369189.254 [Default:CheckIn](INFO): Running loop #3 2023-10-03T21:39:49.254Z,1696369189.254 [Default:CheckIn] Running Loop=3 2023-10-03T21:39:49.254Z,1696369189.254 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T21:39:49.254Z,1696369189.254 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T21:39:51.614Z,1696369191.614 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213949.00,A,3648.14534,N,12147.22576,W,0.719,48.56,031023,,,A*40 2023-10-03T21:39:51.617Z,1696369191.617 [NAL9602](INFO): GPS fix at 20231003T213949: (36.802422, -121.787096) 2023-10-03T21:39:51.652Z,1696369191.652 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T21:39:51.652Z,1696369191.652 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T21:39:57.635Z,1696369197.635 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231003T211430/Courier0010.lzma 2023-10-03T21:39:58.637Z,1696369198.637 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0010.lzma.bak 2023-10-03T21:39:58.637Z,1696369198.637 [DataOverHttps](INFO): SBD MOMSN=18979443 2023-10-03T21:40:15.389Z,1696369215.389 [DataOverHttps](INFO): Sending 936 bytes from file Logs/20231003T211430/Express0011.lzma 2023-10-03T21:40:16.389Z,1696369216.389 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0011.lzma.bak 2023-10-03T21:40:16.389Z,1696369216.389 [DataOverHttps](INFO): SBD MOMSN=18979446 2023-10-03T21:40:18.723Z,1696369218.723 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T21:40:18.727Z,1696369218.727 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T21:40:18.727Z,1696369218.727 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T21:40:24.332Z,1696369224.332 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T21:41:47.971Z,1696369307.971 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T21:41:47.972Z,1696369307.972 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:41:47.983Z,1696369307.983 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:41:48.395Z,1696369308.395 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:41:48.395Z,1696369308.395 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T21:43:27.084Z,1696369407.084 [DAT](INFO): DAT read: user:6>Rx Time:21:43:34.1885 2023-10-03T21:43:27.850Z,1696369407.850 [DAT](INFO): DAT read: 21:43:34.1885 LVL= 24432, 25201, 23026, 29651, AGC= 86, IDX= 46,-0.17,-0.297,-1.476,-0.699,-1.578, PHS= 1.289, 0.096, 0.939, RAW= 133.4, -37.7, CAL= 143.0, -37.2, ROT= 43.0, -37.2 2023-10-03T21:43:27.851Z,1696369407.851 [DAT](INFO): got valid direction response: 21:43:34.1885 LVL= 24432, 25201, 23026, 29651, AGC= 86, IDX= 46,-0.17,-0.297,-1.476,-0.699,-1.578, PHS= 1.289, 0.096, 0.939, RAW= 133.4, -37.7, CAL= 143.0, -37.2, ROT= 43.0, -37.2 2023-10-03T21:43:27.852Z,1696369407.852 [DAT](INFO): DAT read: 2023-10-03T21:43:27.852Z,1696369407.852 [DAT](INFO): DAT read: $Error in header 2023-10-03T21:43:27.853Z,1696369407.853 [DAT](INFO): Received a bad header 2023-10-03T21:43:27.863Z,1696369407.863 [DAT](INFO): #Rx 1: Read direction message, but no range. 2023-10-03T21:43:27.865Z,1696369407.865 [DAT](INFO): direction in FSK: [0.582545,0.543232,0.604599] 2023-10-03T21:43:37.569Z,1696369417.569 [DataOverHttps](IMPORTANT): SBD MTMSN=20231003T214336 2023-10-03T21:43:44.979Z,1696369424.979 [DataOverHttps](INFO): Received command: ibit 2023-10-03T21:43:44.000Z,1696369425.000 [CommandExec](IMPORTANT): got command ibit 2023-10-03T21:43:45.166Z,1696369425.166 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-10-03T21:43:45.166Z,1696369425.166 [IBIT](IMPORTANT): Beginning control surface checks. 2023-10-03T21:43:45.173Z,1696369425.173 [CBIT](IMPORTANT): Beginning ground fault scan 2023-10-03T21:43:46.737Z,1696369426.737 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214346.00,A,3648.15161,N,12147.22038,W,0.019,355.34,031023,,,A*74 2023-10-03T21:43:46.740Z,1696369426.740 [NAL9602](INFO): GPS fix at 20231003T214346: (36.802527, -121.787006) 2023-10-03T21:43:56.173Z,1696369436.173 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000459 CHAN A1 (24V): 0.000020 CHAN A2 (12V): 0.000074 CHAN A3 (5V): -0.002415 CHAN B0 (3.3V): -0.000048 CHAN B1 (3.15aV): -0.000093 CHAN B2 (3.15bV): -0.000219 CHAN B3 (GND): -0.000854 OPEN: 0.006959 Full Scale: +/- 1 mA 2023-10-03T21:44:12.598Z,1696369452.598 [NAL9602](INFO): SBD MO Status=0, MOMSN=7640, MT Status=0, MTMSN=0 2023-10-03T21:44:12.598Z,1696369452.598 [NAL9602](INFO): No messages in MT queue 2023-10-03T21:44:13.806Z,1696369453.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214413.00,A,3648.15161,N,12147.22055,W,0.039,355.34,031023,,,D*7F 2023-10-03T21:44:13.808Z,1696369453.808 [NAL9602](INFO): GPS fix at 20231003T214413: (36.802527, -121.787009) 2023-10-03T21:44:16.642Z,1696369456.642 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214415.00,A,3648.15163,N,12147.22053,W,0.019,355.34,031023,,,D*7F 2023-10-03T21:44:16.644Z,1696369456.644 [NAL9602](INFO): GPS fix at 20231003T214415: (36.802527, -121.787009) 2023-10-03T21:44:19.866Z,1696369459.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214419.00,A,3648.15168,N,12147.22049,W,0.019,355.34,031023,,,D*73 2023-10-03T21:44:19.868Z,1696369459.868 [NAL9602](INFO): GPS fix at 20231003T214419: (36.802528, -121.787008) 2023-10-03T21:44:22.694Z,1696369462.694 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214422.00,A,3648.15163,N,12147.22049,W,0.039,355.34,031023,,,D*72 2023-10-03T21:44:22.697Z,1696369462.697 [NAL9602](INFO): GPS fix at 20231003T214422: (36.802527, -121.787008) 2023-10-03T21:44:25.925Z,1696369465.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214425.00,A,3648.15157,N,12147.22055,W,0.019,355.34,031023,,,D*7D 2023-10-03T21:44:25.928Z,1696369465.928 [NAL9602](INFO): GPS fix at 20231003T214425: (36.802526, -121.787009) 2023-10-03T21:44:28.755Z,1696369468.755 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214428.00,A,3648.15154,N,12147.22056,W,0.000,355.34,031023,,,D*78 2023-10-03T21:44:28.757Z,1696369468.757 [NAL9602](INFO): GPS fix at 20231003T214428: (36.802526, -121.787009) 2023-10-03T21:44:31.220Z,1696369471.220 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802525 Longitude: -121.787010 2023-10-03T21:44:31.637Z,1696369471.637 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2023-10-03T21:44:31.986Z,1696369471.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214431.00,A,3648.15153,N,12147.22059,W,0.058,355.34,031023,,,D*75 2023-10-03T21:44:31.989Z,1696369471.989 [NAL9602](INFO): GPS fix at 20231003T214431: (36.802526, -121.787010) 2023-10-03T21:44:32.009Z,1696369472.009 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-10-03T21:44:32.009Z,1696369472.009 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-10-03T21:44:32.010Z,1696369472.010 [IBIT](IMPORTANT): Pressure:10.104760 PSI 2023-10-03T21:44:32.010Z,1696369472.010 [IBIT](IMPORTANT): Humidity:17.452082 % 2023-10-03T21:44:32.421Z,1696369472.421 [IBIT](IMPORTANT): Vehicle Pitch:0.322209 degrees 2023-10-03T21:44:32.421Z,1696369472.421 [IBIT](IMPORTANT): Vehicle Roll:-5.598027 degrees 2023-10-03T21:44:32.422Z,1696369472.422 [IBIT](IMPORTANT): Vehicle Heading:202.272949 degrees 2023-10-03T21:44:32.835Z,1696369472.835 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-10-03T21:44:32.836Z,1696369472.836 [IBIT](IMPORTANT): buoyancyNeutral: 300.106323 cc 2023-10-03T21:44:32.836Z,1696369472.836 [IBIT](IMPORTANT): massDefault: 0.677690 cm 2023-10-03T21:44:32.836Z,1696369472.836 [IBIT](IMPORTANT): stopDepth: 112.000000 m 2023-10-03T21:44:32.836Z,1696369472.836 [IBIT](IMPORTANT): abortDepth: 130.000000 m 2023-10-03T21:44:32.837Z,1696369472.837 [IBIT](IMPORTANT): IBIT PASSED 2023-10-03T21:44:48.972Z,1696369488.972 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T21:44:48.972Z,1696369488.972 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:44:49.003Z,1696369489.003 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:44:49.411Z,1696369489.411 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:44:49.411Z,1696369489.411 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T21:45:04.312Z,1696369504.312 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T21:45:19.265Z,1696369519.265 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T21:45:19.265Z,1696369519.265 [Default:CheckIn:C.Wait] Stopped 2023-10-03T21:45:19.265Z,1696369519.265 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T21:45:19.265Z,1696369519.265 [Default:CheckIn:D] Running Loop=1 2023-10-03T21:45:19.678Z,1696369519.678 [Default:CheckIn:D] Stopped 2023-10-03T21:45:19.678Z,1696369519.678 [Default:CheckIn:E] Running Loop=1 2023-10-03T21:45:20.094Z,1696369520.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.961440 min 2023-10-03T21:45:20.094Z,1696369520.094 [Default:CheckIn:E] Stopped 2023-10-03T21:45:20.094Z,1696369520.094 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T21:45:20.094Z,1696369520.094 [Default:CheckIn] Stopped 2023-10-03T21:45:20.095Z,1696369520.095 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T21:45:20.095Z,1696369520.095 [Default:CheckIn](INFO): Running loop #4 2023-10-03T21:45:20.095Z,1696369520.095 [Default:CheckIn] Running Loop=4 2023-10-03T21:45:20.095Z,1696369520.095 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T21:45:20.095Z,1696369520.095 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T21:45:22.083Z,1696369522.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214521.00,A,3648.15073,N,12147.22052,W,0.039,355.34,031023,,,D*7A 2023-10-03T21:45:22.085Z,1696369522.085 [NAL9602](INFO): GPS fix at 20231003T214521: (36.802512, -121.787009) 2023-10-03T21:45:22.100Z,1696369522.100 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T21:45:22.100Z,1696369522.100 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T21:45:28.971Z,1696369528.971 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231003T211430/Courier0016.lzma 2023-10-03T21:45:29.973Z,1696369529.973 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0016.lzma.bak 2023-10-03T21:45:29.973Z,1696369529.973 [DataOverHttps](INFO): SBD MOMSN=18979475 2023-10-03T21:45:32.590Z,1696369532.590 [NAL9602](INFO): SBD MO Status=0, MOMSN=7641, MT Status=0, MTMSN=0 2023-10-03T21:45:32.590Z,1696369532.590 [NAL9602](INFO): No messages in MT queue 2023-10-03T21:45:40.668Z,1696369540.668 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 4. 2023-10-03T21:45:40.671Z,1696369540.671 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2023-10-03T21:45:47.005Z,1696369547.005 [DataOverHttps](INFO): Sending 783 bytes from file Logs/20231003T211430/Express0017.lzma 2023-10-03T21:45:48.005Z,1696369548.005 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0017.lzma.bak 2023-10-03T21:45:48.005Z,1696369548.005 [DataOverHttps](INFO): SBD MOMSN=18979482 2023-10-03T21:45:50.381Z,1696369550.381 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T21:45:50.381Z,1696369550.381 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T21:45:50.382Z,1696369550.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T21:46:03.292Z,1696369563.292 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T21:47:49.957Z,1696369669.957 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T21:47:49.957Z,1696369669.957 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:47:49.988Z,1696369669.988 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:47:50.374Z,1696369670.374 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:47:50.374Z,1696369670.374 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T21:50:50.961Z,1696369850.961 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T21:50:50.961Z,1696369850.961 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:50:50.962Z,1696369850.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T21:50:50.962Z,1696369850.962 [Default:CheckIn:C.Wait] Stopped 2023-10-03T21:50:50.962Z,1696369850.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T21:50:50.962Z,1696369850.962 [Default:CheckIn:D] Running Loop=1 2023-10-03T21:50:50.980Z,1696369850.980 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:50:51.389Z,1696369851.389 [Default:CheckIn:D] Stopped 2023-10-03T21:50:51.389Z,1696369851.389 [Default:CheckIn:E] Running Loop=1 2023-10-03T21:50:51.410Z,1696369851.410 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:50:51.410Z,1696369851.410 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T21:50:51.769Z,1696369851.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.489948 min 2023-10-03T21:50:51.769Z,1696369851.769 [Default:CheckIn:E] Stopped 2023-10-03T21:50:51.769Z,1696369851.769 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T21:50:51.769Z,1696369851.769 [Default:CheckIn] Stopped 2023-10-03T21:50:51.769Z,1696369851.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T21:50:51.769Z,1696369851.769 [Default:CheckIn](INFO): Running loop #5 2023-10-03T21:50:51.769Z,1696369851.769 [Default:CheckIn] Running Loop=5 2023-10-03T21:50:51.770Z,1696369851.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T21:50:51.770Z,1696369851.770 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T21:50:53.770Z,1696369853.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215053.00,A,3648.15029,N,12147.22012,W,0.039,355.34,031023,,,D*70 2023-10-03T21:50:53.772Z,1696369853.772 [NAL9602](INFO): GPS fix at 20231003T215053: (36.802505, -121.787002) 2023-10-03T21:50:53.819Z,1696369853.819 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T21:50:53.831Z,1696369853.831 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T21:51:01.820Z,1696369861.820 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20231003T211430/Courier0019.lzma 2023-10-03T21:51:02.821Z,1696369862.821 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0019.lzma.bak 2023-10-03T21:51:02.821Z,1696369862.821 [DataOverHttps](INFO): SBD MOMSN=18979508 2023-10-03T21:51:04.861Z,1696369864.861 [NAL9602](INFO): SBD MO Status=0, MOMSN=7642, MT Status=0, MTMSN=0 2023-10-03T21:51:04.861Z,1696369864.861 [NAL9602](INFO): No messages in MT queue 2023-10-03T21:51:18.720Z,1696369878.720 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231003T211430/Express0021.lzma 2023-10-03T21:51:19.717Z,1696369879.717 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0021.lzma.bak 2023-10-03T21:51:19.717Z,1696369879.717 [DataOverHttps](INFO): SBD MOMSN=18979511 2023-10-03T21:51:21.853Z,1696369881.853 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T21:51:21.853Z,1696369881.853 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T21:51:21.853Z,1696369881.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T21:51:35.552Z,1696369895.552 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T21:53:52.128Z,1696370032.128 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T21:53:52.128Z,1696370032.128 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:53:52.139Z,1696370032.139 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:53:52.554Z,1696370032.554 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:53:52.554Z,1696370032.554 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T21:56:22.411Z,1696370182.411 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T21:56:22.411Z,1696370182.411 [Default:CheckIn:C.Wait] Stopped 2023-10-03T21:56:22.412Z,1696370182.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T21:56:22.412Z,1696370182.412 [Default:CheckIn:D] Running Loop=1 2023-10-03T21:56:22.810Z,1696370182.810 [Default:CheckIn:D] Stopped 2023-10-03T21:56:22.810Z,1696370182.810 [Default:CheckIn:E] Running Loop=1 2023-10-03T21:56:23.208Z,1696370183.208 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.013643 min 2023-10-03T21:56:23.208Z,1696370183.208 [Default:CheckIn:E] Stopped 2023-10-03T21:56:23.209Z,1696370183.209 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T21:56:23.209Z,1696370183.209 [Default:CheckIn] Stopped 2023-10-03T21:56:23.209Z,1696370183.209 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T21:56:23.209Z,1696370183.209 [Default:CheckIn](INFO): Running loop #6 2023-10-03T21:56:23.209Z,1696370183.209 [Default:CheckIn] Running Loop=6 2023-10-03T21:56:23.209Z,1696370183.209 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T21:56:23.209Z,1696370183.209 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T21:56:25.220Z,1696370185.220 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215624.00,A,3648.21547,N,12147.15522,W,3.266,19.44,031023,,,D*49 2023-10-03T21:56:25.222Z,1696370185.222 [NAL9602](INFO): GPS fix at 20231003T215624: (36.803591, -121.785920) 2023-10-03T21:56:25.236Z,1696370185.236 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T21:56:25.236Z,1696370185.236 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T21:56:33.231Z,1696370193.231 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231003T211430/Courier0025.lzma 2023-10-03T21:56:34.233Z,1696370194.233 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0025.lzma.bak 2023-10-03T21:56:34.233Z,1696370194.233 [DataOverHttps](INFO): SBD MOMSN=18979522 2023-10-03T21:56:37.342Z,1696370197.342 [NAL9602](INFO): SBD MO Status=0, MOMSN=7643, MT Status=0, MTMSN=0 2023-10-03T21:56:37.342Z,1696370197.342 [NAL9602](INFO): No messages in MT queue 2023-10-03T21:56:53.171Z,1696370213.171 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T21:56:53.171Z,1696370213.171 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:56:53.190Z,1696370213.190 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:56:53.591Z,1696370213.591 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20231003T211430/Express0026.lzma 2023-10-03T21:56:53.612Z,1696370213.612 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:56:53.612Z,1696370213.612 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T21:56:54.593Z,1696370214.593 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0026.lzma.bak 2023-10-03T21:56:54.593Z,1696370214.593 [DataOverHttps](INFO): SBD MOMSN=18979525 2023-10-03T21:56:57.651Z,1696370217.651 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T21:56:57.651Z,1696370217.651 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T21:56:57.682Z,1696370217.682 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T21:57:08.112Z,1696370228.112 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T21:59:54.164Z,1696370394.164 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T21:59:54.164Z,1696370394.164 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T21:59:54.179Z,1696370394.179 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T21:59:54.589Z,1696370394.589 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T21:59:54.589Z,1696370394.589 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:01:58.208Z,1696370518.208 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:01:58.208Z,1696370518.208 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:01:58.209Z,1696370518.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:01:58.209Z,1696370518.209 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:01:58.606Z,1696370518.606 [Default:CheckIn:D] Stopped 2023-10-03T22:01:58.606Z,1696370518.606 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:01:59.009Z,1696370519.009 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.610234 min 2023-10-03T22:01:59.009Z,1696370519.009 [Default:CheckIn:E] Stopped 2023-10-03T22:01:59.010Z,1696370519.010 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:01:59.010Z,1696370519.010 [Default:CheckIn] Stopped 2023-10-03T22:01:59.010Z,1696370519.010 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:01:59.010Z,1696370519.010 [Default:CheckIn](INFO): Running loop #7 2023-10-03T22:01:59.010Z,1696370519.010 [Default:CheckIn] Running Loop=7 2023-10-03T22:01:59.010Z,1696370519.010 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:01:59.010Z,1696370519.010 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:02:01.019Z,1696370521.019 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220200.00,A,3648.50914,N,12147.19483,W,6.337,237.53,031023,,,D*7F 2023-10-03T22:02:01.022Z,1696370521.022 [NAL9602](INFO): GPS fix at 20231003T220200: (36.808486, -121.786580) 2023-10-03T22:02:01.036Z,1696370521.036 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:02:01.036Z,1696370521.036 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:02:07.935Z,1696370527.935 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231003T211430/Courier0028.lzma 2023-10-03T22:02:08.937Z,1696370528.937 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0028.lzma.bak 2023-10-03T22:02:08.937Z,1696370528.937 [DataOverHttps](INFO): SBD MOMSN=18979530 2023-10-03T22:02:14.354Z,1696370534.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=7644, MT Status=0, MTMSN=0 2023-10-03T22:02:14.354Z,1696370534.354 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:02:25.671Z,1696370545.671 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20231003T211430/Express0029.lzma 2023-10-03T22:02:26.673Z,1696370546.673 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0029.lzma.bak 2023-10-03T22:02:26.673Z,1696370546.673 [DataOverHttps](INFO): SBD MOMSN=18979533 2023-10-03T22:02:28.929Z,1696370548.929 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T22:02:28.929Z,1696370548.929 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T22:02:28.929Z,1696370548.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T22:02:45.051Z,1696370565.051 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T22:02:55.182Z,1696370575.182 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T22:02:55.182Z,1696370575.182 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:02:55.236Z,1696370575.236 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:02:55.659Z,1696370575.659 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:02:55.659Z,1696370575.659 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T22:05:56.196Z,1696370756.196 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T22:05:56.196Z,1696370756.196 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:05:56.211Z,1696370756.211 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:05:56.598Z,1696370756.598 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:05:56.598Z,1696370756.598 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:07:29.512Z,1696370849.512 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:07:29.513Z,1696370849.513 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:07:29.513Z,1696370849.513 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:07:29.513Z,1696370849.513 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:07:29.921Z,1696370849.921 [Default:CheckIn:D] Stopped 2023-10-03T22:07:29.921Z,1696370849.921 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:07:30.325Z,1696370850.325 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.132149 min 2023-10-03T22:07:30.325Z,1696370850.325 [Default:CheckIn:E] Stopped 2023-10-03T22:07:30.325Z,1696370850.325 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:07:30.325Z,1696370850.325 [Default:CheckIn] Stopped 2023-10-03T22:07:30.325Z,1696370850.325 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:07:30.326Z,1696370850.326 [Default:CheckIn](INFO): Running loop #8 2023-10-03T22:07:30.326Z,1696370850.326 [Default:CheckIn] Running Loop=8 2023-10-03T22:07:30.326Z,1696370850.326 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:07:30.326Z,1696370850.326 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:07:32.334Z,1696370852.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220731.00,A,3648.16357,N,12148.36602,W,12.091,249.84,031023,,,D*47 2023-10-03T22:07:32.345Z,1696370852.345 [NAL9602](INFO): GPS fix at 20231003T220731: (36.802726, -121.806100) 2023-10-03T22:07:32.364Z,1696370852.364 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:07:32.364Z,1696370852.364 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:07:40.067Z,1696370860.067 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231003T211430/Courier0031.lzma 2023-10-03T22:07:41.069Z,1696370861.069 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0031.lzma.bak 2023-10-03T22:07:41.069Z,1696370861.069 [DataOverHttps](INFO): SBD MOMSN=18979546 2023-10-03T22:07:44.045Z,1696370864.045 [NAL9602](INFO): SBD MO Status=0, MOMSN=7645, MT Status=0, MTMSN=0 2023-10-03T22:07:44.045Z,1696370864.045 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:07:57.843Z,1696370877.843 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20231003T211430/Express0032.lzma 2023-10-03T22:07:58.845Z,1696370878.845 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0032.lzma.bak 2023-10-03T22:07:58.845Z,1696370878.845 [DataOverHttps](INFO): SBD MOMSN=18979549 2023-10-03T22:08:01.075Z,1696370881.075 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T22:08:01.076Z,1696370881.076 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T22:08:01.076Z,1696370881.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T22:08:14.748Z,1696370894.748 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T22:08:57.199Z,1696370937.199 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T22:08:57.199Z,1696370937.199 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:08:57.210Z,1696370937.210 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:08:57.626Z,1696370937.626 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:08:57.626Z,1696370937.626 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T22:11:58.186Z,1696371118.186 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T22:11:58.186Z,1696371118.186 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:11:58.198Z,1696371118.198 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:11:58.621Z,1696371118.621 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:11:58.621Z,1696371118.621 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:13:01.614Z,1696371181.614 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:13:01.614Z,1696371181.614 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:13:01.614Z,1696371181.614 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:13:01.614Z,1696371181.614 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:13:02.027Z,1696371182.027 [Default:CheckIn:D] Stopped 2023-10-03T22:13:02.027Z,1696371182.027 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:13:02.416Z,1696371182.416 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.667253 min 2023-10-03T22:13:02.416Z,1696371182.416 [Default:CheckIn:E] Stopped 2023-10-03T22:13:02.416Z,1696371182.416 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:13:02.416Z,1696371182.416 [Default:CheckIn] Stopped 2023-10-03T22:13:02.416Z,1696371182.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:13:02.417Z,1696371182.417 [Default:CheckIn](INFO): Running loop #9 2023-10-03T22:13:02.417Z,1696371182.417 [Default:CheckIn] Running Loop=9 2023-10-03T22:13:02.417Z,1696371182.417 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:13:02.417Z,1696371182.417 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:13:04.425Z,1696371184.425 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221303.00,A,3647.97163,N,12149.96869,W,12.907,264.11,031023,,,D*4D 2023-10-03T22:13:04.428Z,1696371184.428 [NAL9602](INFO): GPS fix at 20231003T221303: (36.799527, -121.832812) 2023-10-03T22:13:04.442Z,1696371184.442 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:13:04.442Z,1696371184.442 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:13:13.752Z,1696371193.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-03T22:13:16.144Z,1696371196.144 [NAL9602](INFO): SBD MO Status=0, MOMSN=7646, MT Status=0, MTMSN=0 2023-10-03T22:13:16.145Z,1696371196.145 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:13:44.876Z,1696371224.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-03T22:13:46.843Z,1696371226.843 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T22:14:15.000Z,1696371256.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-03T22:14:23.335Z,1696371263.335 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231003T211430/Courier0034.lzma 2023-10-03T22:14:24.337Z,1696371264.337 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0034.lzma.bak 2023-10-03T22:14:24.337Z,1696371264.337 [DataOverHttps](INFO): SBD MOMSN=18979553 2023-10-03T22:14:42.135Z,1696371282.135 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20231003T211430/Express0035.lzma 2023-10-03T22:14:43.138Z,1696371283.138 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0035.lzma.bak 2023-10-03T22:14:43.138Z,1696371283.138 [DataOverHttps](INFO): SBD MOMSN=18979556 2023-10-03T22:14:45.466Z,1696371285.466 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T22:14:45.466Z,1696371285.466 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T22:14:45.466Z,1696371285.466 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T22:14:59.237Z,1696371299.237 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T22:14:59.237Z,1696371299.237 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:14:59.248Z,1696371299.248 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:14:59.666Z,1696371299.666 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:14:59.666Z,1696371299.666 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T22:15:46.500Z,1696371346.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-03T22:18:00.223Z,1696371480.223 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T22:18:00.223Z,1696371480.223 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:18:00.254Z,1696371480.254 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:18:00.664Z,1696371480.664 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:18:00.664Z,1696371480.664 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:19:46.089Z,1696371586.089 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:19:46.090Z,1696371586.090 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:19:46.090Z,1696371586.090 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:19:46.090Z,1696371586.090 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:19:46.493Z,1696371586.493 [Default:CheckIn:D] Stopped 2023-10-03T22:19:46.493Z,1696371586.493 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:19:46.898Z,1696371586.898 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.408358 min 2023-10-03T22:19:46.898Z,1696371586.898 [Default:CheckIn:E] Stopped 2023-10-03T22:19:46.899Z,1696371586.899 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:19:46.899Z,1696371586.899 [Default:CheckIn] Stopped 2023-10-03T22:19:46.899Z,1696371586.899 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:19:46.899Z,1696371586.899 [Default:CheckIn](INFO): Running loop #10 2023-10-03T22:19:46.899Z,1696371586.899 [Default:CheckIn] Running Loop=10 2023-10-03T22:19:46.899Z,1696371586.899 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:19:46.899Z,1696371586.899 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:19:48.906Z,1696371588.906 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221948.00,A,3647.78620,N,12150.76659,W,1.633,110.37,031023,,,D*7C 2023-10-03T22:19:48.917Z,1696371588.917 [NAL9602](INFO): GPS fix at 20231003T221948: (36.796437, -121.846110) 2023-10-03T22:19:48.932Z,1696371588.932 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:19:48.932Z,1696371588.932 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:19:56.011Z,1696371596.011 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231003T211430/Courier0037.lzma 2023-10-03T22:19:57.013Z,1696371597.013 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0037.lzma.bak 2023-10-03T22:19:57.013Z,1696371597.013 [DataOverHttps](INFO): SBD MOMSN=18979566 2023-10-03T22:20:03.042Z,1696371603.042 [NAL9602](INFO): SBD MO Status=0, MOMSN=7647, MT Status=0, MTMSN=0 2023-10-03T22:20:03.042Z,1696371603.042 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:20:13.896Z,1696371613.896 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20231003T211430/Express0038.lzma 2023-10-03T22:20:14.897Z,1696371614.897 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0038.lzma.bak 2023-10-03T22:20:14.897Z,1696371614.897 [DataOverHttps](INFO): SBD MOMSN=18979569 2023-10-03T22:20:17.215Z,1696371617.215 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T22:20:17.215Z,1696371617.215 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T22:20:17.216Z,1696371617.216 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T22:20:33.752Z,1696371633.752 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T22:21:01.252Z,1696371661.252 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T22:21:01.252Z,1696371661.252 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:21:01.263Z,1696371661.263 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:21:01.646Z,1696371661.646 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:21:01.646Z,1696371661.646 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T22:24:02.252Z,1696371842.252 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T22:24:02.252Z,1696371842.252 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:24:02.263Z,1696371842.263 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:24:02.682Z,1696371842.682 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:24:02.682Z,1696371842.682 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:25:17.769Z,1696371917.769 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:25:17.769Z,1696371917.769 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:25:17.769Z,1696371917.769 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:25:17.770Z,1696371917.770 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:25:18.177Z,1696371918.177 [Default:CheckIn:D] Stopped 2023-10-03T22:25:18.177Z,1696371918.177 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:25:18.578Z,1696371918.578 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.936422 min 2023-10-03T22:25:18.578Z,1696371918.578 [Default:CheckIn:E] Stopped 2023-10-03T22:25:18.578Z,1696371918.578 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:25:18.578Z,1696371918.578 [Default:CheckIn] Stopped 2023-10-03T22:25:18.578Z,1696371918.578 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:25:18.579Z,1696371918.579 [Default:CheckIn](INFO): Running loop #11 2023-10-03T22:25:18.579Z,1696371918.579 [Default:CheckIn] Running Loop=11 2023-10-03T22:25:18.579Z,1696371918.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:25:18.579Z,1696371918.579 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:25:20.585Z,1696371920.585 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222519.00,A,3647.72823,N,12150.62280,W,1.866,108.86,031023,,,D*78 2023-10-03T22:25:20.596Z,1696371920.596 [NAL9602](INFO): GPS fix at 20231003T222519: (36.795471, -121.843713) 2023-10-03T22:25:20.621Z,1696371920.621 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:25:20.621Z,1696371920.621 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:25:27.487Z,1696371927.487 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231003T211430/Courier0040.lzma 2023-10-03T22:25:28.489Z,1696371928.489 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0040.lzma.bak 2023-10-03T22:25:28.489Z,1696371928.489 [DataOverHttps](INFO): SBD MOMSN=18979573 2023-10-03T22:25:36.430Z,1696371936.430 [NAL9602](INFO): SBD MO Status=0, MOMSN=7648, MT Status=0, MTMSN=0 2023-10-03T22:25:36.430Z,1696371936.430 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:25:45.379Z,1696371945.379 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20231003T211430/Express0041.lzma 2023-10-03T22:25:46.381Z,1696371946.381 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0041.lzma.bak 2023-10-03T22:25:46.381Z,1696371946.381 [DataOverHttps](INFO): SBD MOMSN=18979576 2023-10-03T22:25:48.584Z,1696371948.584 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T22:25:48.584Z,1696371948.584 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T22:25:48.584Z,1696371948.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T22:26:07.132Z,1696371967.132 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T22:27:03.296Z,1696372023.296 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T22:27:03.296Z,1696372023.296 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:27:03.307Z,1696372023.307 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:27:03.715Z,1696372023.715 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:27:03.715Z,1696372023.715 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T22:30:04.300Z,1696372204.300 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T22:30:04.300Z,1696372204.300 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:30:04.348Z,1696372204.348 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:30:04.753Z,1696372204.753 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:30:04.753Z,1696372204.753 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:30:49.145Z,1696372249.145 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:30:49.145Z,1696372249.145 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:30:49.145Z,1696372249.145 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:30:49.145Z,1696372249.145 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:30:49.552Z,1696372249.552 [Default:CheckIn:D] Stopped 2023-10-03T22:30:49.553Z,1696372249.553 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.459342 min 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn:E] Stopped 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn] Stopped 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn](INFO): Running loop #12 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn] Running Loop=12 2023-10-03T22:30:49.950Z,1696372249.950 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:30:49.951Z,1696372249.951 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:30:51.950Z,1696372251.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223051.00,A,3647.75790,N,12150.94504,W,5.890,287.02,031023,,,D*77 2023-10-03T22:30:51.954Z,1696372251.954 [NAL9602](INFO): GPS fix at 20231003T223051: (36.795965, -121.849084) 2023-10-03T22:30:51.978Z,1696372251.978 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:30:51.978Z,1696372251.978 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:31:02.311Z,1696372262.311 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20231003T211430/Courier0043.lzma 2023-10-03T22:31:03.313Z,1696372263.313 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0043.lzma.bak 2023-10-03T22:31:03.313Z,1696372263.313 [DataOverHttps](INFO): SBD MOMSN=18979586 2023-10-03T22:31:04.369Z,1696372264.369 [NAL9602](INFO): SBD MO Status=0, MOMSN=7649, MT Status=0, MTMSN=0 2023-10-03T22:31:04.369Z,1696372264.369 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:31:26.231Z,1696372286.231 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20231003T211430/Express0044.lzma 2023-10-03T22:31:27.233Z,1696372287.233 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0044.lzma.bak 2023-10-03T22:31:27.233Z,1696372287.233 [DataOverHttps](INFO): SBD MOMSN=18979589 2023-10-03T22:31:29.409Z,1696372289.409 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T22:31:29.410Z,1696372289.410 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T22:31:29.410Z,1696372289.410 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T22:31:35.031Z,1696372295.031 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T22:31:59.371Z,1696372319.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-10-03T22:33:05.148Z,1696372385.148 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T22:33:05.148Z,1696372385.148 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:33:05.160Z,1696372385.160 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:33:05.564Z,1696372385.564 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:33:05.564Z,1696372385.564 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T22:36:06.150Z,1696372566.150 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T22:36:06.150Z,1696372566.150 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:36:06.201Z,1696372566.201 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:36:06.595Z,1696372566.595 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:36:06.595Z,1696372566.595 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:36:29.973Z,1696372589.973 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:36:29.973Z,1696372589.973 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:36:29.973Z,1696372589.973 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:36:29.973Z,1696372589.973 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:36:30.369Z,1696372590.369 [Default:CheckIn:D] Stopped 2023-10-03T22:36:30.369Z,1696372590.369 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:36:30.784Z,1696372590.784 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.139624 min 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn:E] Stopped 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn] Stopped 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn](INFO): Running loop #13 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn] Running Loop=13 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:36:30.785Z,1696372590.785 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:36:32.786Z,1696372592.786 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223632.00,A,3647.73521,N,12150.83690,W,0.914,124.56,031023,,,D*71 2023-10-03T22:36:32.789Z,1696372592.789 [NAL9602](INFO): GPS fix at 20231003T223632: (36.795587, -121.847282) 2023-10-03T22:36:32.822Z,1696372592.822 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:36:32.822Z,1696372592.822 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:36:40.027Z,1696372600.027 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231003T211430/Courier0046.lzma 2023-10-03T22:36:41.029Z,1696372601.029 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0046.lzma.bak 2023-10-03T22:36:41.029Z,1696372601.029 [DataOverHttps](INFO): SBD MOMSN=18979593 2023-10-03T22:36:48.162Z,1696372608.162 [NAL9602](INFO): SBD MO Status=0, MOMSN=7650, MT Status=0, MTMSN=0 2023-10-03T22:36:48.162Z,1696372608.162 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:37:02.907Z,1696372622.907 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20231003T211430/Express0047.lzma 2023-10-03T22:37:03.909Z,1696372623.909 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Express0047.lzma.bak 2023-10-03T22:37:03.909Z,1696372623.909 [DataOverHttps](INFO): SBD MOMSN=18979596 2023-10-03T22:37:06.022Z,1696372626.022 [Default:CheckIn:Read_Iridium] Stopped 2023-10-03T22:37:06.022Z,1696372626.022 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-03T22:37:06.022Z,1696372626.022 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-03T22:37:18.904Z,1696372638.904 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-03T22:39:07.244Z,1696372747.244 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2023-10-03T22:39:07.244Z,1696372747.244 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:39:07.256Z,1696372747.256 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:39:07.663Z,1696372747.663 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:39:07.663Z,1696372747.663 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2023-10-03T22:40:47.208Z,1696372847.208 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-10-03T22:42:06.696Z,1696372926.696 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-03T22:42:06.696Z,1696372926.696 [Default:CheckIn:C.Wait] Stopped 2023-10-03T22:42:06.696Z,1696372926.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-03T22:42:06.696Z,1696372926.696 [Default:CheckIn:D] Running Loop=1 2023-10-03T22:42:07.085Z,1696372927.085 [Default:CheckIn:D] Stopped 2023-10-03T22:42:07.085Z,1696372927.085 [Default:CheckIn:E] Running Loop=1 2023-10-03T22:42:07.486Z,1696372927.486 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.751554 min 2023-10-03T22:42:07.486Z,1696372927.486 [Default:CheckIn:E] Stopped 2023-10-03T22:42:07.486Z,1696372927.486 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-03T22:42:07.486Z,1696372927.486 [Default:CheckIn] Stopped 2023-10-03T22:42:07.486Z,1696372927.486 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-03T22:42:07.486Z,1696372927.486 [Default:CheckIn](INFO): Running loop #14 2023-10-03T22:42:07.486Z,1696372927.486 [Default:CheckIn] Running Loop=14 2023-10-03T22:42:07.487Z,1696372927.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-03T22:42:07.487Z,1696372927.487 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-03T22:42:08.304Z,1696372928.304 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2023-10-03T22:42:08.304Z,1696372928.304 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2023-10-03T22:42:08.332Z,1696372928.332 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2023-10-03T22:42:08.705Z,1696372928.705 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2023-10-03T22:42:08.705Z,1696372928.705 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2023-10-03T22:42:09.496Z,1696372929.496 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224208.00,A,3647.68396,N,12150.71152,W,0.486,126.43,031023,,,D*7F 2023-10-03T22:42:09.498Z,1696372929.498 [NAL9602](INFO): GPS fix at 20231003T224208: (36.794733, -121.845192) 2023-10-03T22:42:09.513Z,1696372929.513 [Default:CheckIn:Read_GPS] Stopped 2023-10-03T22:42:09.513Z,1696372929.513 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-03T22:42:16.747Z,1696372936.747 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231003T211430/Courier0049.lzma 2023-10-03T22:42:17.749Z,1696372937.749 [DataOverHttps](INFO): Moved sent file to Logs/20231003T211430/Courier0049.lzma.bak 2023-10-03T22:42:17.750Z,1696372937.750 [DataOverHttps](IMPORTANT): SBD MOMSN=18979608, MTMSN=20231003T224217 2023-10-03T22:42:24.442Z,1696372944.442 [NAL9602](INFO): SBD MO Status=0, MOMSN=7651, MT Status=0, MTMSN=0 2023-10-03T22:42:24.442Z,1696372944.442 [NAL9602](INFO): No messages in MT queue 2023-10-03T22:42:26.147Z,1696372946.147 [DataOverHttps](INFO): Received command: restart logs