2022-03-16T18:57:32.086Z,1647457052.086 [Supervisor](DEBUG): Initializing supervisor. 2022-03-16T18:57:32.114Z,1647457052.114 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-03-16T18:57:32.115Z,1647457052.115 [SyncHandler](INFO): Protected caller Thread ID is 4117 2022-03-16T18:57:32.115Z,1647457052.115 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-03-16T18:57:32.116Z,1647457052.116 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-03-16T18:57:32.117Z,1647457052.117 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4118 2022-03-16T18:57:32.121Z,1647457052.121 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-03-16T18:57:32.147Z,1647457052.147 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-03-16T18:57:32.148Z,1647457052.148 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-03-16T18:57:32.148Z,1647457052.148 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4119 2022-03-16T18:57:32.153Z,1647457052.153 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-03-16T18:57:32.154Z,1647457052.154 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-03-16T18:57:32.154Z,1647457052.154 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4120 2022-03-16T18:57:32.156Z,1647457052.156 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-03-16T18:57:32.157Z,1647457052.157 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-03-16T18:57:32.157Z,1647457052.157 [logger ThreadHandler](INFO): Protected caller Thread ID is 4121 2022-03-16T18:57:32.161Z,1647457052.161 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-03-16T18:57:32.161Z,1647457052.161 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-03-16T18:57:32.163Z,1647457052.163 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-03-16T18:57:32.574Z,1647457052.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-03-16T18:57:32.575Z,1647457052.575 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-03-16T18:57:32.783Z,1647457052.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-03-16T18:57:32.784Z,1647457052.784 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-03-16T18:57:32.965Z,1647457052.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-03-16T18:57:32.966Z,1647457052.966 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-03-16T18:57:33.070Z,1647457053.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-03-16T18:57:33.070Z,1647457053.070 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-03-16T18:57:33.424Z,1647457053.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-03-16T18:57:33.425Z,1647457053.425 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-03-16T18:57:33.752Z,1647457053.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-03-16T18:57:33.753Z,1647457053.753 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-03-16T18:57:33.846Z,1647457053.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-03-16T18:57:33.847Z,1647457053.847 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-03-16T18:57:34.054Z,1647457054.054 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-03-16T18:57:34.055Z,1647457054.055 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-03-16T18:57:34.624Z,1647457054.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-03-16T18:57:34.625Z,1647457054.625 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-03-16T18:57:34.973Z,1647457054.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-03-16T18:57:34.973Z,1647457054.973 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-03-16T18:57:35.052Z,1647457055.052 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-03-16T18:57:35.260Z,1647457055.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-03-16T18:57:35.261Z,1647457055.261 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-03-16T18:57:35.386Z,1647457055.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-03-16T18:57:35.386Z,1647457055.386 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-03-16T18:57:36.085Z,1647457056.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-03-16T18:57:36.086Z,1647457056.086 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-03-16T18:57:36.163Z,1647457056.163 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-03-16T18:57:36.440Z,1647457056.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-03-16T18:57:36.442Z,1647457056.442 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2022-03-16T18:57:36.443Z,1647457056.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2022-03-16T18:57:36.617Z,1647457056.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2022-03-16T18:57:36.708Z,1647457056.708 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2022-03-16T18:57:36.818Z,1647457056.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2022-03-16T18:57:36.916Z,1647457056.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2022-03-16T18:57:37.073Z,1647457057.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2022-03-16T18:57:37.360Z,1647457057.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2022-03-16T18:57:37.484Z,1647457057.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2022-03-16T18:57:37.648Z,1647457057.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2022-03-16T18:57:37.960Z,1647457057.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2022-03-16T18:57:38.223Z,1647457058.223 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-03-16T18:57:38.224Z,1647457058.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2022-03-16T18:57:38.316Z,1647457058.316 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-03-16T18:57:38.332Z,1647457058.332 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-03-16T18:57:38.647Z,1647457058.647 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-03-16T18:57:38.647Z,1647457058.647 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-03-16T18:57:38.758Z,1647457058.758 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-03-16T18:57:38.758Z,1647457058.758 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-03-16T18:57:38.796Z,1647457058.796 [DeadReckonUsingSpeedCalculator] Loaded 2022-03-16T18:57:38.796Z,1647457058.796 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2022-03-16T18:57:38.809Z,1647457058.809 [NavChart] Loaded 2022-03-16T18:57:38.809Z,1647457058.809 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-03-16T18:57:38.814Z,1647457058.814 [UniversalFixResidualReporter] Loaded 2022-03-16T18:57:38.815Z,1647457058.815 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-03-16T18:57:38.815Z,1647457058.815 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-03-16T18:57:38.816Z,1647457058.816 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-03-16T18:57:38.826Z,1647457058.826 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-03-16T18:57:38.826Z,1647457058.826 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-03-16T18:57:39.016Z,1647457059.016 [CTD_Seabird] Loaded 2022-03-16T18:57:39.017Z,1647457059.017 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-03-16T18:57:39.018Z,1647457059.018 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406614E0 2022-03-16T18:57:39.018Z,1647457059.018 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4203 2022-03-16T18:57:39.037Z,1647457059.037 [PAR_Licor] Loaded 2022-03-16T18:57:39.038Z,1647457059.038 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-03-16T18:57:39.068Z,1647457059.068 [WetLabsBB2FL] Loaded 2022-03-16T18:57:39.068Z,1647457059.068 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-03-16T18:57:39.069Z,1647457059.069 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406914E0 2022-03-16T18:57:39.070Z,1647457059.070 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4204 2022-03-16T18:57:39.070Z,1647457059.070 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-03-16T18:57:39.071Z,1647457059.071 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-03-16T18:57:39.122Z,1647457059.122 [VerticalControl](DEBUG): Construct VerticalControl. 2022-03-16T18:57:39.182Z,1647457059.182 [VerticalControl] Loaded 2022-03-16T18:57:39.182Z,1647457059.182 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-03-16T18:57:39.185Z,1647457059.185 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-03-16T18:57:39.224Z,1647457059.224 [HorizontalControl] Loaded 2022-03-16T18:57:39.224Z,1647457059.224 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-03-16T18:57:39.227Z,1647457059.227 [SpeedControl](DEBUG): Construct SpeedControl. 2022-03-16T18:57:39.230Z,1647457059.230 [SpeedControl] Loaded 2022-03-16T18:57:39.230Z,1647457059.230 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-03-16T18:57:39.233Z,1647457059.233 [LoopControl](DEBUG): Construct LoopControl. 2022-03-16T18:57:39.233Z,1647457059.233 [LoopControl] Loaded 2022-03-16T18:57:39.233Z,1647457059.233 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-03-16T18:57:39.234Z,1647457059.234 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-03-16T18:57:39.234Z,1647457059.234 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-03-16T18:57:39.283Z,1647457059.283 [DepthRateCalculator] Loaded 2022-03-16T18:57:39.283Z,1647457059.283 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-03-16T18:57:39.288Z,1647457059.288 [PitchRateCalculator] Loaded 2022-03-16T18:57:39.288Z,1647457059.288 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-03-16T18:57:39.297Z,1647457059.297 [SpeedCalculator] Loaded 2022-03-16T18:57:39.298Z,1647457059.298 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-03-16T18:57:39.302Z,1647457059.302 [YawRateCalculator] Loaded 2022-03-16T18:57:39.302Z,1647457059.302 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-03-16T18:57:39.320Z,1647457059.320 [ElevatorOffsetCalculator] Loaded 2022-03-16T18:57:39.320Z,1647457059.320 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-03-16T18:57:39.320Z,1647457059.320 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-03-16T18:57:39.321Z,1647457059.321 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-03-16T18:57:39.415Z,1647457059.415 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-03-16T18:57:39.415Z,1647457059.415 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-03-16T18:57:39.473Z,1647457059.473 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-03-16T18:57:39.474Z,1647457059.474 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-03-16T18:57:39.596Z,1647457059.596 [BuoyancyServo] Loaded 2022-03-16T18:57:39.596Z,1647457059.596 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-03-16T18:57:39.615Z,1647457059.615 [ElevatorServo] Loaded 2022-03-16T18:57:39.616Z,1647457059.616 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-03-16T18:57:39.634Z,1647457059.634 [MassServo] Loaded 2022-03-16T18:57:39.635Z,1647457059.635 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-03-16T18:57:39.653Z,1647457059.653 [RudderServo] Loaded 2022-03-16T18:57:39.653Z,1647457059.653 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-03-16T18:57:39.667Z,1647457059.667 [ThrusterHE] Loaded 2022-03-16T18:57:39.668Z,1647457059.668 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2022-03-16T18:57:39.668Z,1647457059.668 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-03-16T18:57:39.669Z,1647457059.669 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-03-16T18:57:39.776Z,1647457059.776 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-03-16T18:57:39.777Z,1647457059.777 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-03-16T18:57:40.166Z,1647457060.166 [AHRS_M2] Loaded 2022-03-16T18:57:40.167Z,1647457060.167 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-03-16T18:57:40.202Z,1647457060.202 [BackseatComponent] Loaded 2022-03-16T18:57:40.202Z,1647457060.202 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2022-03-16T18:57:40.203Z,1647457060.203 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A874E0 2022-03-16T18:57:40.203Z,1647457060.203 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4205 2022-03-16T18:57:40.206Z,1647457060.206 [LcmUniversalReporter] Loaded 2022-03-16T18:57:40.206Z,1647457060.206 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2022-03-16T18:57:41.041Z,1647457061.041 [BPC1] Loaded 2022-03-16T18:57:41.041Z,1647457061.041 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-03-16T18:57:41.117Z,1647457061.117 [DataOverHttps] Loaded 2022-03-16T18:57:41.117Z,1647457061.117 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-03-16T18:57:41.118Z,1647457061.118 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AB74E0 2022-03-16T18:57:41.118Z,1647457061.118 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4206 2022-03-16T18:57:41.138Z,1647457061.138 [Depth_Keller] Loaded 2022-03-16T18:57:41.138Z,1647457061.138 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-03-16T18:57:41.197Z,1647457061.197 [NAL9602] Loaded 2022-03-16T18:57:41.197Z,1647457061.197 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-03-16T18:57:41.226Z,1647457061.226 [Onboard] Loaded 2022-03-16T18:57:41.227Z,1647457061.227 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-03-16T18:57:41.228Z,1647457061.228 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AE74E0 2022-03-16T18:57:41.228Z,1647457061.228 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4207 2022-03-16T18:57:41.239Z,1647457061.239 [Power24vConverter] Loaded 2022-03-16T18:57:41.240Z,1647457061.240 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-03-16T18:57:41.253Z,1647457061.253 [Radio_Surface] Loaded 2022-03-16T18:57:41.253Z,1647457061.253 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-03-16T18:57:41.254Z,1647457061.254 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B174E0 2022-03-16T18:57:41.254Z,1647457061.254 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4208 2022-03-16T18:57:41.339Z,1647457061.339 [DAT] Loaded 2022-03-16T18:57:41.339Z,1647457061.339 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2022-03-16T18:57:41.340Z,1647457061.340 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0 2022-03-16T18:57:41.341Z,1647457061.341 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4209 2022-03-16T18:57:41.341Z,1647457061.341 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-03-16T18:57:41.342Z,1647457061.342 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-03-16T18:57:41.482Z,1647457061.482 [SBIT](DEBUG): Construct Startup Built In Test. 2022-03-16T18:57:41.491Z,1647457061.491 [SBIT] Loaded 2022-03-16T18:57:41.492Z,1647457061.492 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-03-16T18:57:41.494Z,1647457061.494 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-03-16T18:57:41.508Z,1647457061.508 [IBIT] Loaded 2022-03-16T18:57:41.508Z,1647457061.508 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-03-16T18:57:41.514Z,1647457061.514 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-03-16T18:57:41.617Z,1647457061.617 [CBIT] Loaded 2022-03-16T18:57:41.617Z,1647457061.617 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-03-16T18:57:41.618Z,1647457061.618 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-03-16T18:57:41.618Z,1647457061.618 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-03-16T18:57:41.645Z,1647457061.645 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-03-16T18:57:41.650Z,1647457061.650 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-03-16T18:57:41.653Z,1647457061.653 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-03-16T18:57:41.664Z,1647457061.664 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-03-16T18:57:41.665Z,1647457061.665 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD54E0 2022-03-16T18:57:41.665Z,1647457061.665 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4210 2022-03-16T18:57:41.670Z,1647457061.670 [Supervisor](INFO): Main Thread ID is 4116 2022-03-16T18:57:41.670Z,1647457061.670 [Supervisor](DEBUG): Running supervisor. 2022-03-16T18:57:41.670Z,1647457061.670 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4211 2022-03-16T18:57:41.671Z,1647457061.671 [CommandExec](INFO): Initializing the command executive. 2022-03-16T18:57:41.672Z,1647457061.672 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4212 2022-03-16T18:57:41.674Z,1647457061.674 [controlThread ThreadHandler](INFO): Handler Thread ID is 4213 2022-03-16T18:57:41.675Z,1647457061.675 [controlThread](DEBUG): Initializing ControlThread 2022-03-16T18:57:41.676Z,1647457061.676 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T18:57:41.677Z,1647457061.677 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-03-16T18:57:41.677Z,1647457061.677 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-03-16T18:57:41.681Z,1647457061.681 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-03-16T18:57:41.683Z,1647457061.683 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-03-16T18:57:41.684Z,1647457061.684 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-03-16T18:57:41.684Z,1647457061.684 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-03-16T18:57:41.685Z,1647457061.685 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-03-16T18:57:41.685Z,1647457061.685 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-03-16T18:57:41.685Z,1647457061.685 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-03-16T18:57:41.685Z,1647457061.685 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-03-16T18:57:41.686Z,1647457061.686 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-03-16T18:57:41.690Z,1647457061.690 [SBIT](INFO): Initialize SBIT Component. 2022-03-16T18:57:41.691Z,1647457061.691 [SBIT](IMPORTANT): git: 2022-03-09-10-g7024677d1 2022-03-16T18:57:41.691Z,1647457061.691 [SBIT](INFO): git hash: 7024677d185b27b3bbb21ddd45e40affc00d1f5d 2022-03-16T18:57:41.691Z,1647457061.691 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-03-16T18:57:41.692Z,1647457061.692 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2022-03-16T18:57:41.693Z,1647457061.693 [SBIT](INFO): Beginning SBIT in 38.000000 seconds. 2022-03-16T18:57:41.694Z,1647457061.694 [IBIT](INFO): Initialize IBIT Component. 2022-03-16T18:57:41.695Z,1647457061.695 [CBIT](DEBUG): Initialize CBIT Component. 2022-03-16T18:57:41.696Z,1647457061.696 [logger ThreadHandler](INFO): Handler Thread ID is 4214 2022-03-16T18:57:41.709Z,1647457061.709 [CBIT](DEBUG): Initialized mux pins. 2022-03-16T18:57:41.709Z,1647457061.709 [CBIT](DEBUG): Initializing the watchdog timer. 2022-03-16T18:57:41.714Z,1647457061.714 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4215 2022-03-16T18:57:41.714Z,1647457061.714 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-03-16T18:57:41.719Z,1647457061.719 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4217 2022-03-16T18:57:41.722Z,1647457061.722 [WetLabsBB2FL](INFO): Powering up 2022-03-16T18:57:41.723Z,1647457061.723 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4219 2022-03-16T18:57:41.730Z,1647457061.730 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4220 2022-03-16T18:57:41.731Z,1647457061.731 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-03-16T18:57:41.733Z,1647457061.733 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-03-16T18:57:41.734Z,1647457061.734 [CBIT](DEBUG): Initializing heartbeat. 2022-03-16T18:57:41.742Z,1647457061.742 [Onboard ThreadHandler](INFO): Handler Thread ID is 4221 2022-03-16T18:57:41.772Z,1647457061.772 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4222 2022-03-16T18:57:41.782Z,1647457061.782 [DAT ThreadHandler](INFO): Handler Thread ID is 4223 2022-03-16T18:57:41.782Z,1647457061.782 [DAT](INFO): Powering up 2022-03-16T18:57:41.782Z,1647457061.782 [DAT](DEBUG): Initializing DAT. 2022-03-16T18:57:41.786Z,1647457061.786 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4225 2022-03-16T18:57:41.789Z,1647457061.789 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-03-16T18:57:41.790Z,1647457061.790 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-03-16T18:57:41.790Z,1647457061.790 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-03-16T18:57:41.790Z,1647457061.790 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-03-16T18:57:41.790Z,1647457061.790 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-03-16T18:57:41.790Z,1647457061.790 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-03-16T18:57:41.790Z,1647457061.790 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2022-03-16T18:57:41.790Z,1647457061.790 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2022-03-16T18:57:41.791Z,1647457061.791 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-03-16T18:57:41.791Z,1647457061.791 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-03-16T18:57:41.791Z,1647457061.791 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2022-03-16T18:57:41.791Z,1647457061.791 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2022-03-16T18:57:41.791Z,1647457061.791 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2022-03-16T18:57:41.791Z,1647457061.791 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2022-03-16T18:57:41.792Z,1647457061.792 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2022-03-16T18:57:41.792Z,1647457061.792 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2022-03-16T18:57:41.805Z,1647457061.805 [CBIT](DEBUG): Deactivating GF circuits. 2022-03-16T18:57:41.805Z,1647457061.805 [CBIT](DEBUG): Deactivating emergency mode. 2022-03-16T18:57:41.841Z,1647457061.841 [CBIT](DEBUG): Backplane powered. 2022-03-16T18:57:41.842Z,1647457061.842 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-03-16T18:57:41.854Z,1647457061.854 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-03-16T18:57:41.885Z,1647457061.885 [MissionManager](DEBUG): 2022-03-16T18:57:41.886Z,1647457061.886 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-03-16T18:57:41.954Z,1647457061.954 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-03-16T18:57:41.956Z,1647457061.956 [Default:A.Wait](DEBUG): Construct Wait. 2022-03-16T18:57:41.958Z,1647457061.958 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-03-16T18:57:42.013Z,1647457062.013 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-03-16T18:57:42.016Z,1647457062.016 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-03-16T18:57:42.029Z,1647457062.029 [Default:E.Execute](DEBUG): Construct Execute. 2022-03-16T18:57:42.044Z,1647457062.044 [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 2022-03-16T18:57:42.049Z,1647457062.049 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2022-03-16T18:57:42.073Z,1647457062.073 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-03-16T18:57:42.164Z,1647457062.164 [Power24vConverter](INFO): Powering up. 2022-03-16T18:57:42.173Z,1647457062.173 [Radio_Surface](INFO): Powering up 2022-03-16T18:57:42.242Z,1647457062.242 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-03-16T18:57:42.246Z,1647457062.246 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-03-16T18:57:42.247Z,1647457062.247 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-03-16T18:57:42.249Z,1647457062.249 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-03-16T18:57:42.251Z,1647457062.251 [MassServo](DEBUG): Initializing EZServoServo. 2022-03-16T18:57:42.313Z,1647457062.313 [MassServo](DEBUG): Initializing MassServo. 2022-03-16T18:57:42.314Z,1647457062.314 [RudderServo](DEBUG): Initializing EZServoServo. 2022-03-16T18:57:42.401Z,1647457062.401 [RudderServo](DEBUG): Initializing RudderServo. 2022-03-16T18:57:42.402Z,1647457062.402 [ThrusterHE](DEBUG): Initializing EZServoServo. 2022-03-16T18:57:42.433Z,1647457062.433 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2022-03-16T18:57:44.333Z,1647457064.333 [WetLabsBB2FL](INFO): Powering down 2022-03-16T18:57:47.963Z,1647457067.963 [ThrusterHE](ERROR): Zero Speed Commanded. 2022-03-16T18:57:51.658Z,1647457071.658 [CommandExec](IMPORTANT): got command strobe off 2022-03-16T18:57:51.659Z,1647457071.659 [CommandExec](IMPORTANT): Deactivating strobe 2022-03-16T18:57:53.374Z,1647457073.374 [DAT](INFO): DAT read: 2022-03-16T18:57:53.375Z,1647457073.375 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2022-03-16T18:57:54.886Z,1647457074.886 [DAT](INFO): DAT read: MF Frequency Band 2022-03-16T18:57:54.887Z,1647457074.887 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2022-03-16T18:57:54.888Z,1647457074.888 [DAT](INFO): DAT read: Mar 16 2022 18:57:49 2022-03-16T18:57:56.146Z,1647457076.146 [DAT](INFO): DAT read: Features enabled [Bearing] 2022-03-16T18:57:56.147Z,1647457076.147 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2022-03-16T18:57:56.148Z,1647457076.148 [DAT](INFO): commRate: 800 2022-03-16T18:57:58.217Z,1647457078.217 [DAT](INFO): entering command mode 2022-03-16T18:57:58.414Z,1647457078.414 [DAT](INFO): DAT read: 2022-03-16T18:57:58.414Z,1647457078.414 [DAT](INFO): DAT read: user:1> 2022-03-16T18:57:58.415Z,1647457078.415 [DAT](INFO): setting verbose to 3 2022-03-16T18:57:58.666Z,1647457078.666 [DAT](INFO): DAT read: user:1> 2022-03-16T18:57:58.667Z,1647457078.667 [DAT](INFO): DAT read: Verbose | 3 2022-03-16T18:57:58.668Z,1647457078.668 [DAT](INFO): set verbose to 3 2022-03-16T18:57:58.668Z,1647457078.668 [DAT](INFO): setting DatVerbose to 27440 2022-03-16T18:57:58.918Z,1647457078.918 [DAT](INFO): DAT read: user:2> 2022-03-16T18:57:58.919Z,1647457078.919 [DAT](INFO): DAT read: DatVerbose | 27440 2022-03-16T18:57:58.919Z,1647457078.919 [DAT](INFO): set DatVerbose to 27440 2022-03-16T18:57:58.919Z,1647457078.919 [DAT](INFO): setting transmit power to 8 2022-03-16T18:57:59.170Z,1647457079.170 [DAT](INFO): DAT read: user:3> 2022-03-16T18:57:59.171Z,1647457079.171 [DAT](INFO): DAT read: TxPower | 8 (Max) 2022-03-16T18:57:59.171Z,1647457079.171 [DAT](INFO): set transmit power to 8 2022-03-16T18:57:59.172Z,1647457079.172 [DAT](INFO): setting local address to 10 2022-03-16T18:57:59.422Z,1647457079.422 [DAT](INFO): DAT read: user:4> 2022-03-16T18:57:59.423Z,1647457079.423 [DAT](INFO): DAT read: LocalAddr | 10 2022-03-16T18:57:59.423Z,1647457079.423 [DAT](INFO): set local address to 10 2022-03-16T18:57:59.424Z,1647457079.424 [DAT](INFO): Setting time to: 18:57:59 And date to:3/16/2022 2022-03-16T18:57:59.674Z,1647457079.674 [DAT](INFO): DAT read: user:5> 2022-03-16T18:57:59.675Z,1647457079.675 [DAT](INFO): DAT read: Wed Mar 16, 2022 18:57:59 2022-03-16T18:57:59.675Z,1647457079.675 [DAT](INFO): Local DAT time set to Wed Mar 16, 2022 18:57:59 2022-03-16T18:58:11.563Z,1647457091.563 [NAL9602](INFO): Powering up NAL9602 2022-03-16T18:58:20.492Z,1647457100.492 [SBIT](IMPORTANT): Beginning Startup BIT 2022-03-16T18:58:20.496Z,1647457100.496 [CBIT](IMPORTANT): Beginning ground fault scan 2022-03-16T18:58:22.475Z,1647457102.475 [NAL9602](INFO): NAL9602 initialized 2022-03-16T18:58:31.447Z,1647457111.447 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000118 CHAN A1 (24V): 0.000733 CHAN A2 (12V): -0.005132 CHAN A3 (5V): -0.002855 CHAN B0 (3.3V): -0.001535 CHAN B1 (3.15aV): -0.001871 CHAN B2 (3.15bV): -0.002030 CHAN B3 (GND): -0.000519 OPEN: 0.004611 Full Scale: +/- 1 mA 2022-03-16T18:59:14.607Z,1647457154.607 [SBIT](IMPORTANT): SBIT PASSED 2022-03-16T18:59:14.608Z,1647457154.608 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-03-16T18:59:14.608Z,1647457154.608 [SBIT](IMPORTANT): BackseatComponent.needs24v=1 bool; 2022-03-16T18:59:14.609Z,1647457154.609 [SBIT](IMPORTANT): BuoyancyServo.fastPumpCoefficient=6 none; 2022-03-16T18:59:14.609Z,1647457154.609 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere; 2022-03-16T18:59:14.609Z,1647457154.609 [SBIT](IMPORTANT): DAT.sbdAddress=21 enum; 2022-03-16T18:59:14.609Z,1647457154.609 [SBIT](IMPORTANT): DAT.surfaceThreshold=3 meter; 2022-03-16T18:59:14.609Z,1647457154.609 [SBIT](IMPORTANT): DAT.verbosity=3 count; 2022-03-16T18:59:14.609Z,1647457154.609 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 10.000000 gigabyte; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 100.000000 count; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_size 2.500000 millimeter; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): Express linearApproximation _.eyeris_particle_counts 100.000000 count; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool; 2022-03-16T18:59:14.610Z,1647457154.610 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=115.741138 cubic_centimeter; 2022-03-16T18:59:14.611Z,1647457154.611 [SBIT](IMPORTANT): VerticalControl.massDefault=-4.597572 millimeter; 2022-03-16T18:59:15.014Z,1647457155.014 [MissionManager](IMPORTANT): Started mission Startup 2022-03-16T18:59:15.015Z,1647457155.015 [Startup] Running Loop=1 2022-03-16T18:59:15.015Z,1647457155.015 [Startup](DEBUG): Aggregate::initialize Startup 2022-03-16T18:59:15.015Z,1647457155.015 [Startup:A.GoToSurface] Running Loop=1 2022-03-16T18:59:15.015Z,1647457155.015 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-03-16T18:59:15.016Z,1647457155.016 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-03-16T18:59:15.016Z,1647457155.016 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-03-16T18:59:15.016Z,1647457155.016 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-03-16T18:59:15.017Z,1647457155.017 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-03-16T18:59:15.017Z,1647457155.017 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-03-16T18:59:15.019Z,1647457155.019 [Startup:StartupSatComms] Running Loop=1 2022-03-16T18:59:15.019Z,1647457155.019 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-03-16T18:59:15.019Z,1647457155.019 [Startup:StartupSatComms:A] Running Loop=1 2022-03-16T18:59:15.408Z,1647457155.408 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-03-16T19:00:09.711Z,1647457209.711 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005351 2022-03-16T19:00:15.223Z,1647457215.223 [Startup:StartupSatComms:A](INFO): Timed out from 2022-03-16T18:59:15.0Z 2022-03-16T19:00:15.223Z,1647457215.223 [Startup:StartupSatComms:A] Stopped 2022-03-16T19:00:15.223Z,1647457215.223 [Startup:StartupSatComms:B] Running Loop=1 2022-03-16T19:00:15.621Z,1647457215.621 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-03-16T19:00:25.099Z,1647457225.099 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220316T184724/Courier0007.lzma 2022-03-16T19:00:26.099Z,1647457226.099 [DataOverHttps](INFO): Moved sent file to Logs/20220316T184724/Courier0007.lzma.bak 2022-03-16T19:00:26.099Z,1647457226.099 [DataOverHttps](INFO): SBD MOMSN=16574607 2022-03-16T19:00:41.955Z,1647457241.955 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-03-16T19:00:41.955Z,1647457241.955 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:00:41.956Z,1647457241.956 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2022-03-16T19:00:41.956Z,1647457241.956 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:00:41.974Z,1647457241.974 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:00:41.974Z,1647457241.974 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:00:42.340Z,1647457242.340 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:00:42.340Z,1647457242.340 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-03-16T19:00:42.340Z,1647457242.340 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:00:42.341Z,1647457242.341 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2022-03-16T19:00:42.722Z,1647457242.722 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:00:45.664Z,1647457245.664 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220316T185731/Courier0000.lzma 2022-03-16T19:00:46.503Z,1647457246.503 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Courier0000.lzma.bak 2022-03-16T19:00:46.503Z,1647457246.503 [DataOverHttps](INFO): SBD MOMSN=16574609 2022-03-16T19:01:02.241Z,1647457262.241 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220316T184724/Express0008.lzma 2022-03-16T19:01:03.243Z,1647457263.243 [DataOverHttps](INFO): Moved sent file to Logs/20220316T184724/Express0008.lzma.bak 2022-03-16T19:01:03.243Z,1647457263.243 [DataOverHttps](INFO): SBD MOMSN=16574612 2022-03-16T19:01:15.470Z,1647457275.470 [Startup:StartupSatComms:B](INFO): Timed out from 2022-03-16T19:00:15.2Z 2022-03-16T19:01:15.470Z,1647457275.470 [Startup:StartupSatComms:B] Stopped 2022-03-16T19:01:15.470Z,1647457275.470 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-03-16T19:01:15.471Z,1647457275.471 [Startup:StartupSatComms] Stopped 2022-03-16T19:01:15.471Z,1647457275.471 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-03-16T19:01:15.471Z,1647457275.471 [Startup](INFO): Completed Startup 2022-03-16T19:01:15.472Z,1647457275.472 [MissionManager](INFO): Startup is completed. 2022-03-16T19:01:15.472Z,1647457275.472 [MissionManager](INFO): Uninitializing Mission Startup 2022-03-16T19:01:15.472Z,1647457275.472 [Startup] Stopped 2022-03-16T19:01:15.472Z,1647457275.472 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-03-16T19:01:15.472Z,1647457275.472 [Startup:A.GoToSurface] Stopped 2022-03-16T19:01:15.472Z,1647457275.472 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-03-16T19:01:15.880Z,1647457275.880 [MissionManager](IMPORTANT): Started mission Default 2022-03-16T19:01:15.881Z,1647457275.881 [Default] Running Loop=1 2022-03-16T19:01:15.881Z,1647457275.881 [Default](DEBUG): Aggregate::initialize Default 2022-03-16T19:01:15.885Z,1647457275.885 [Default:B.GoToSurface] Running Loop=1 2022-03-16T19:01:15.886Z,1647457275.886 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-03-16T19:01:15.887Z,1647457275.887 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-03-16T19:01:15.889Z,1647457275.889 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-03-16T19:01:15.893Z,1647457275.893 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-03-16T19:01:15.894Z,1647457275.894 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-03-16T19:01:15.894Z,1647457275.894 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-03-16T19:01:15.894Z,1647457275.894 [Default:A.Wait] Running Loop=1 2022-03-16T19:01:15.894Z,1647457275.894 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-03-16T19:01:19.154Z,1647457279.154 [DataOverHttps](INFO): Sending 1017 bytes from file Logs/20220316T185731/Express0001.lzma 2022-03-16T19:01:20.155Z,1647457280.155 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Express0001.lzma.bak 2022-03-16T19:01:20.155Z,1647457280.155 [DataOverHttps](INFO): SBD MOMSN=16574615 2022-03-16T19:01:29.216Z,1647457289.216 [Default:A.Wait](INFO): Done Waiting. 2022-03-16T19:01:29.216Z,1647457289.216 [Default:A.Wait] Stopped 2022-03-16T19:01:29.216Z,1647457289.216 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-03-16T19:01:29.640Z,1647457289.640 [Default:CheckIn] Running Loop=1 2022-03-16T19:01:29.641Z,1647457289.641 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-16T19:01:29.641Z,1647457289.641 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-16T19:01:30.015Z,1647457290.015 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-03-16T19:02:54.660Z,1647457374.660 [CommandExec](IMPORTANT): got command configSet AHRS_M2.readAccelerations 1 2022-03-16T19:02:54.660Z,1647457374.660 [CommandExec](FAULT): Incomplete syntax. Try: help configSet 2022-03-16T19:03:08.916Z,1647457388.916 [CommandExec](IMPORTANT): got command configSet AHRS_M2.readAccelerations 1 bool 2022-03-16T19:03:22.928Z,1647457402.928 [CommandExec](IMPORTANT): got command configSet AHRS_M2.readMagnetics 1 bool 2022-03-16T19:03:25.528Z,1647457405.528 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-03-16T19:03:36.542Z,1647457416.542 [CommandExec](IMPORTANT): got command configSet AHRS_M2.readAngularVelocities 1 bool 2022-03-16T19:03:42.960Z,1647457422.960 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-03-16T19:03:42.960Z,1647457422.960 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:03:42.961Z,1647457422.961 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2022-03-16T19:03:42.961Z,1647457422.961 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:03:42.971Z,1647457422.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:03:42.972Z,1647457422.972 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:03:43.411Z,1647457423.411 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:03:43.411Z,1647457423.411 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-03-16T19:03:43.411Z,1647457423.411 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:03:43.411Z,1647457423.411 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2022-03-16T19:03:43.716Z,1647457423.716 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:05:28.755Z,1647457528.755 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-03-16T19:05:28.773Z,1647457528.773 [BPC1](INFO): Received data from all battery sticks. 2022-03-16T19:06:29.779Z,1647457589.779 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-16T19:01:29.6Z 2022-03-16T19:06:29.779Z,1647457589.779 [Default:CheckIn:Read_GPS] Stopped 2022-03-16T19:06:29.779Z,1647457589.779 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-16T19:06:30.180Z,1647457590.180 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-03-16T19:06:37.409Z,1647457597.409 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20220316T185731/Courier0004.lzma 2022-03-16T19:06:38.411Z,1647457598.411 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Courier0004.lzma.bak 2022-03-16T19:06:38.411Z,1647457598.411 [DataOverHttps](INFO): SBD MOMSN=16574644 2022-03-16T19:06:43.964Z,1647457603.964 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-03-16T19:06:43.964Z,1647457603.964 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:06:43.974Z,1647457603.974 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2022-03-16T19:06:43.974Z,1647457603.974 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:06:43.992Z,1647457603.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:06:43.993Z,1647457603.993 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:06:44.364Z,1647457604.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:06:44.364Z,1647457604.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-03-16T19:06:44.370Z,1647457604.370 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:06:44.370Z,1647457604.370 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2022-03-16T19:06:44.733Z,1647457604.733 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:06:54.165Z,1647457614.165 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20220316T185731/Express0005.lzma 2022-03-16T19:06:55.167Z,1647457615.167 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Express0005.lzma.bak 2022-03-16T19:06:55.167Z,1647457615.167 [DataOverHttps](INFO): SBD MOMSN=16574646 2022-03-16T19:06:56.850Z,1647457616.850 [Default:CheckIn:Read_Iridium] Stopped 2022-03-16T19:06:56.850Z,1647457616.850 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-16T19:06:56.850Z,1647457616.850 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-16T19:07:54.400Z,1647457674.400 [CommandExec](IMPORTANT): got command configSet AHRS_M2.verbosity 2 count 2022-03-16T19:07:54.614Z,1647457674.614 [AHRS_M2](INFO): PITCH: -0.383303, ROLL: -3.738248, YAW: 213.529977 (deg) 2022-03-16T19:07:54.615Z,1647457674.615 [AHRS_M2](INFO): Mag: X: -15.658762, Y: 5.698118, Z: 51.308844 (uT) 2022-03-16T19:07:54.615Z,1647457674.615 [AHRS_M2](INFO): Accel: X: 0.072616, Y: -0.640813, Z: 9.858410 (m/s2) 2022-03-16T19:07:54.616Z,1647457674.616 [AHRS_M2](INFO): Ang.Vel: X: 0.006395, Y: -0.002223, Z: -0.003823 (rad/s) 2022-03-16T19:07:54.617Z,1647457674.617 [AHRS_M2](INFO): YAW ERR: 0.182154 (deg), TEMP: 27.818237 (degC) 2022-03-16T19:07:55.013Z,1647457675.013 [AHRS_M2](INFO): PITCH: -0.386678, ROLL: -3.741821, YAW: 213.540673 (deg) 2022-03-16T19:07:55.014Z,1647457675.014 [AHRS_M2](INFO): Mag: X: -15.648619, Y: 5.737283, Z: 51.353717 (uT) 2022-03-16T19:07:55.015Z,1647457675.015 [AHRS_M2](INFO): Accel: X: 0.077018, Y: -0.640929, Z: 9.851930 (m/s2) 2022-03-16T19:07:55.015Z,1647457675.015 [AHRS_M2](INFO): Ang.Vel: X: 0.006127, Y: -0.002481, Z: -0.001964 (rad/s) 2022-03-16T19:07:55.016Z,1647457675.016 [AHRS_M2](INFO): YAW ERR: 0.182219 (deg), TEMP: 27.836597 (degC) 2022-03-16T19:07:55.412Z,1647457675.412 [AHRS_M2](INFO): PITCH: -0.390142, ROLL: -3.738153, YAW: 213.541794 (deg) 2022-03-16T19:07:55.413Z,1647457675.413 [AHRS_M2](INFO): Mag: X: -15.649535, Y: 5.745305, Z: 51.299744 (uT) 2022-03-16T19:07:55.414Z,1647457675.414 [AHRS_M2](INFO): Accel: X: 0.063935, Y: -0.649274, Z: 9.855802 (m/s2) 2022-03-16T19:07:55.414Z,1647457675.414 [AHRS_M2](INFO): Ang.Vel: X: 0.005597, Y: -0.002745, Z: -0.001968 (rad/s) 2022-03-16T19:07:55.415Z,1647457675.415 [AHRS_M2](INFO): YAW ERR: 0.182260 (deg), TEMP: 27.864138 (degC) 2022-03-16T19:07:55.816Z,1647457675.816 [AHRS_M2](INFO): PITCH: -0.391813, ROLL: -3.746025, YAW: 213.560454 (deg) 2022-03-16T19:07:55.817Z,1647457675.817 [AHRS_M2](INFO): Mag: X: -15.593456, Y: 5.801244, Z: 51.309613 (uT) 2022-03-16T19:07:55.817Z,1647457675.817 [AHRS_M2](INFO): Accel: X: 0.059580, Y: -0.644379, Z: 9.862260 (m/s2) 2022-03-16T19:07:55.818Z,1647457675.818 [AHRS_M2](INFO): Ang.Vel: X: 0.006660, Y: -0.003274, Z: -0.000634 (rad/s) 2022-03-16T19:07:55.819Z,1647457675.819 [AHRS_M2](INFO): YAW ERR: 0.182140 (deg), TEMP: 27.839657 (degC) 2022-03-16T19:07:56.220Z,1647457676.220 [AHRS_M2](INFO): PITCH: -0.384192, ROLL: -3.745258, YAW: 213.570986 (deg) 2022-03-16T19:07:56.221Z,1647457676.221 [AHRS_M2](INFO): Mag: X: -15.568729, Y: 5.796768, Z: 51.292407 (uT) 2022-03-16T19:07:56.222Z,1647457676.222 [AHRS_M2](INFO): Accel: X: 0.070385, Y: -0.654054, Z: 9.853322 (m/s2) 2022-03-16T19:07:56.222Z,1647457676.222 [AHRS_M2](INFO): Ang.Vel: X: 0.006397, Y: -0.002754, Z: -0.003558 (rad/s) 2022-03-16T19:07:56.223Z,1647457676.223 [AHRS_M2](INFO): YAW ERR: 0.182250 (deg), TEMP: 27.827417 (degC) 2022-03-16T19:07:56.626Z,1647457676.626 [AHRS_M2](INFO): PITCH: -0.382978, ROLL: -3.748038, YAW: 213.585425 (deg) 2022-03-16T19:07:56.626Z,1647457676.626 [AHRS_M2](INFO): Mag: X: -15.546448, Y: 5.814121, Z: 51.319501 (uT) 2022-03-16T19:07:56.627Z,1647457676.627 [AHRS_M2](INFO): Accel: X: 0.063253, Y: -0.639748, Z: 9.854452 (m/s2) 2022-03-16T19:07:56.628Z,1647457676.628 [AHRS_M2](INFO): Ang.Vel: X: 0.005064, Y: -0.003011, Z: -0.000636 (rad/s) 2022-03-16T19:07:56.628Z,1647457676.628 [AHRS_M2](INFO): YAW ERR: 0.182290 (deg), TEMP: 27.858017 (degC) 2022-03-16T19:07:57.033Z,1647457677.033 [AHRS_M2](INFO): PITCH: -0.380395, ROLL: -3.741666, YAW: 213.570918 (deg) 2022-03-16T19:07:57.034Z,1647457677.034 [AHRS_M2](INFO): Mag: X: -15.557039, Y: 5.788180, Z: 51.280963 (uT) 2022-03-16T19:07:57.034Z,1647457677.034 [AHRS_M2](INFO): Accel: X: 0.067742, Y: -0.634778, Z: 9.863487 (m/s2) 2022-03-16T19:07:57.035Z,1647457677.035 [AHRS_M2](INFO): Ang.Vel: X: 0.006388, Y: -0.001942, Z: -0.000901 (rad/s) 2022-03-16T19:07:57.036Z,1647457677.036 [AHRS_M2](INFO): YAW ERR: 0.182159 (deg), TEMP: 27.842718 (degC) 2022-03-16T19:07:57.432Z,1647457677.432 [AHRS_M2](INFO): PITCH: -0.374637, ROLL: -3.741243, YAW: 213.579510 (deg) 2022-03-16T19:07:57.433Z,1647457677.433 [AHRS_M2](INFO): Mag: X: -15.553017, Y: 5.762857, Z: 51.314874 (uT) 2022-03-16T19:07:57.434Z,1647457677.434 [AHRS_M2](INFO): Accel: X: 0.069189, Y: -0.634939, Z: 9.854460 (m/s2) 2022-03-16T19:07:57.434Z,1647457677.434 [AHRS_M2](INFO): Ang.Vel: X: 0.005862, Y: -0.002749, Z: -0.001964 (rad/s) 2022-03-16T19:07:57.435Z,1647457677.435 [AHRS_M2](INFO): YAW ERR: 0.182212 (deg), TEMP: 27.836597 (degC) 2022-03-16T19:07:57.840Z,1647457677.840 [AHRS_M2](INFO): PITCH: -0.382722, ROLL: -3.737986, YAW: 213.557476 (deg) 2022-03-16T19:07:57.841Z,1647457677.841 [AHRS_M2](INFO): Mag: X: -15.593683, Y: 5.738375, Z: 51.429364 (uT) 2022-03-16T19:07:57.842Z,1647457677.842 [AHRS_M2](INFO): Accel: X: 0.069759, Y: -0.646769, Z: 9.859737 (m/s2) 2022-03-16T19:07:57.842Z,1647457677.842 [AHRS_M2](INFO): Ang.Vel: X: 0.006923, Y: -0.001949, Z: -0.002494 (rad/s) 2022-03-16T19:07:57.843Z,1647457677.843 [AHRS_M2](INFO): YAW ERR: 0.182271 (deg), TEMP: 27.821297 (degC) 2022-03-16T19:07:58.240Z,1647457678.240 [AHRS_M2](INFO): PITCH: -0.380693, ROLL: -3.736094, YAW: 213.551028 (deg) 2022-03-16T19:07:58.241Z,1647457678.241 [AHRS_M2](INFO): Mag: X: -15.602382, Y: 5.771421, Z: 51.323151 (uT) 2022-03-16T19:07:58.241Z,1647457678.241 [AHRS_M2](INFO): Accel: X: 0.074818, Y: -0.649390, Z: 9.846823 (m/s2) 2022-03-16T19:07:58.242Z,1647457678.242 [AHRS_M2](INFO): Ang.Vel: X: 0.006386, Y: -0.001676, Z: -0.000635 (rad/s) 2022-03-16T19:07:58.243Z,1647457678.243 [AHRS_M2](INFO): YAW ERR: 0.182293 (deg), TEMP: 27.836597 (degC) 2022-03-16T19:07:58.648Z,1647457678.648 [AHRS_M2](INFO): PITCH: -0.383511, ROLL: -3.736516, YAW: 213.547845 (deg) 2022-03-16T19:07:58.649Z,1647457678.649 [AHRS_M2](INFO): Mag: X: -15.632108, Y: 5.749375, Z: 51.284888 (uT) 2022-03-16T19:07:58.650Z,1647457678.650 [AHRS_M2](INFO): Accel: X: 0.066929, Y: -0.646983, Z: 9.849377 (m/s2) 2022-03-16T19:07:58.651Z,1647457678.651 [AHRS_M2](INFO): Ang.Vel: X: 0.006128, Y: -0.003542, Z: -0.000101 (rad/s) 2022-03-16T19:07:58.651Z,1647457678.651 [AHRS_M2](INFO): YAW ERR: 0.182159 (deg), TEMP: 27.836597 (degC) 2022-03-16T19:07:59.049Z,1647457679.049 [AHRS_M2](INFO): PITCH: -0.385596, ROLL: -3.742458, YAW: 213.564784 (deg) 2022-03-16T19:07:59.050Z,1647457679.050 [AHRS_M2](INFO): Mag: X: -15.625143, Y: 5.698161, Z: 51.311957 (uT) 2022-03-16T19:07:59.051Z,1647457679.051 [AHRS_M2](INFO): Accel: X: 0.066719, Y: -0.635096, Z: 9.846706 (m/s2) 2022-03-16T19:07:59.051Z,1647457679.051 [AHRS_M2](INFO): Ang.Vel: X: 0.006132, Y: -0.002753, Z: -0.003559 (rad/s) 2022-03-16T19:07:59.052Z,1647457679.052 [AHRS_M2](INFO): YAW ERR: 0.182230 (deg), TEMP: 27.839657 (degC) 2022-03-16T19:07:59.452Z,1647457679.452 [AHRS_M2](INFO): PITCH: -0.387032, ROLL: -3.741264, YAW: 213.561628 (deg) 2022-03-16T19:07:59.453Z,1647457679.453 [AHRS_M2](INFO): Mag: X: -15.653323, Y: 5.732061, Z: 51.285327 (uT) 2022-03-16T19:07:59.454Z,1647457679.454 [AHRS_M2](INFO): Accel: X: 0.071869, Y: -0.642042, Z: 9.857102 (m/s2) 2022-03-16T19:07:59.454Z,1647457679.454 [AHRS_M2](INFO): Ang.Vel: X: 0.006657, Y: -0.001418, Z: -0.003560 (rad/s) 2022-03-16T19:07:59.455Z,1647457679.455 [AHRS_M2](INFO): YAW ERR: 0.182268 (deg), TEMP: 27.833538 (degC) 2022-03-16T19:07:59.856Z,1647457679.856 [AHRS_M2](INFO): PITCH: -0.388264, ROLL: -3.748313, YAW: 213.579769 (deg) 2022-03-16T19:07:59.857Z,1647457679.857 [AHRS_M2](INFO): Mag: X: -15.645428, Y: 5.720330, Z: 51.355377 (uT) 2022-03-16T19:07:59.858Z,1647457679.858 [AHRS_M2](INFO): Accel: X: 0.076538, Y: -0.650917, Z: 9.828762 (m/s2) 2022-03-16T19:07:59.858Z,1647457679.858 [AHRS_M2](INFO): Ang.Vel: X: 0.005867, Y: -0.003021, Z: -0.003293 (rad/s) 2022-03-16T19:07:59.859Z,1647457679.859 [AHRS_M2](INFO): YAW ERR: 0.182144 (deg), TEMP: 27.833538 (degC) 2022-03-16T19:08:00.260Z,1647457680.260 [AHRS_M2](INFO): PITCH: -0.382035, ROLL: -3.740793, YAW: 213.581067 (deg) 2022-03-16T19:08:00.261Z,1647457680.261 [AHRS_M2](INFO): Mag: X: -15.635577, Y: 5.780521, Z: 51.354260 (uT) 2022-03-16T19:08:00.262Z,1647457680.262 [AHRS_M2](INFO): Accel: X: 0.059129, Y: -0.645623, Z: 9.859684 (m/s2) 2022-03-16T19:08:00.262Z,1647457680.262 [AHRS_M2](INFO): Ang.Vel: X: 0.006127, Y: -0.002746, Z: -0.001432 (rad/s) 2022-03-16T19:08:00.263Z,1647457680.263 [AHRS_M2](INFO): YAW ERR: 0.182203 (deg), TEMP: 27.839657 (degC) 2022-03-16T19:08:00.674Z,1647457680.674 [AHRS_M2](INFO): PITCH: -0.381932, ROLL: -3.752247, YAW: 213.605519 (deg) 2022-03-16T19:08:00.674Z,1647457680.674 [AHRS_M2](INFO): Mag: X: -15.598143, Y: 5.788221, Z: 51.297528 (uT) 2022-03-16T19:08:00.675Z,1647457680.675 [AHRS_M2](INFO): Accel: X: 0.058974, Y: -0.652861, Z: 9.855857 (m/s2) 2022-03-16T19:08:00.676Z,1647457680.676 [AHRS_M2](INFO): Ang.Vel: X: 0.006927, Y: -0.002217, Z: -0.003827 (rad/s) 2022-03-16T19:08:00.676Z,1647457680.676 [AHRS_M2](INFO): YAW ERR: 0.182260 (deg), TEMP: 27.842718 (degC) 2022-03-16T19:08:01.073Z,1647457681.073 [AHRS_M2](INFO): PITCH: -0.385382, ROLL: -3.750354, YAW: 213.594864 (deg) 2022-03-16T19:08:01.074Z,1647457681.074 [AHRS_M2](INFO): Mag: X: -15.589793, Y: 5.793110, Z: 51.332349 (uT) 2022-03-16T19:08:01.074Z,1647457681.074 [AHRS_M2](INFO): Accel: X: 0.063934, Y: -0.657709, Z: 9.850752 (m/s2) 2022-03-16T19:08:01.075Z,1647457681.075 [AHRS_M2](INFO): Ang.Vel: X: 0.005856, Y: -0.001416, Z: -0.001698 (rad/s) 2022-03-16T19:08:01.075Z,1647457681.075 [AHRS_M2](INFO): YAW ERR: 0.182160 (deg), TEMP: 27.830477 (degC) 2022-03-16T19:08:01.472Z,1647457681.472 [AHRS_M2](INFO): PITCH: -0.385063, ROLL: -3.741873, YAW: 213.574278 (deg) 2022-03-16T19:08:01.473Z,1647457681.473 [AHRS_M2](INFO): Mag: X: -15.547386, Y: 5.831169, Z: 51.283734 (uT) 2022-03-16T19:08:01.473Z,1647457681.473 [AHRS_M2](INFO): Accel: X: 0.065302, Y: -0.642411, Z: 9.839006 (m/s2) 2022-03-16T19:08:01.474Z,1647457681.474 [AHRS_M2](INFO): Ang.Vel: X: 0.006660, Y: -0.001950, Z: -0.003561 (rad/s) 2022-03-16T19:08:01.475Z,1647457681.475 [AHRS_M2](INFO): YAW ERR: 0.182204 (deg), TEMP: 27.839657 (degC) 2022-03-16T19:08:01.876Z,1647457681.876 [AHRS_M2](INFO): PITCH: -0.384234, ROLL: -3.745306, YAW: 213.584974 (deg) 2022-03-16T19:08:01.877Z,1647457681.877 [AHRS_M2](INFO): Mag: X: -15.539407, Y: 5.819292, Z: 51.349390 (uT) 2022-03-16T19:08:01.877Z,1647457681.877 [AHRS_M2](INFO): Accel: X: 0.061161, Y: -0.638498, Z: 9.857068 (m/s2) 2022-03-16T19:08:01.878Z,1647457681.878 [AHRS_M2](INFO): Ang.Vel: X: 0.006119, Y: -0.002470, Z: 0.001761 (rad/s) 2022-03-16T19:08:01.879Z,1647457681.879 [AHRS_M2](INFO): YAW ERR: 0.182246 (deg), TEMP: 27.830477 (degC) 2022-03-16T19:08:02.310Z,1647457682.310 [AHRS_M2](INFO): PITCH: -0.383259, ROLL: -3.746074, YAW: 213.592692 (deg) 2022-03-16T19:08:02.311Z,1647457682.311 [AHRS_M2](INFO): Mag: X: -15.535632, Y: 5.784138, Z: 51.305115 (uT) 2022-03-16T19:08:02.311Z,1647457682.311 [AHRS_M2](INFO): Accel: X: 0.067380, Y: -0.645738, Z: 9.851952 (m/s2) 2022-03-16T19:08:02.312Z,1647457682.312 [AHRS_M2](INFO): Ang.Vel: X: 0.005332, Y: -0.003017, Z: -0.001697 (rad/s) 2022-03-16T19:08:02.312Z,1647457682.312 [AHRS_M2](INFO): YAW ERR: 0.182272 (deg), TEMP: 27.836597 (degC) 2022-03-16T19:08:02.684Z,1647457682.684 [AHRS_M2](INFO): PITCH: -0.388367, ROLL: -3.750786, YAW: 213.589496 (deg) 2022-03-16T19:08:02.685Z,1647457682.685 [AHRS_M2](INFO): Mag: X: -15.573912, Y: 5.741145, Z: 51.314264 (uT) 2022-03-16T19:08:02.686Z,1647457682.686 [AHRS_M2](INFO): Accel: X: 0.071100, Y: -0.654173, Z: 9.846868 (m/s2) 2022-03-16T19:08:02.686Z,1647457682.686 [AHRS_M2](INFO): Ang.Vel: X: 0.006922, Y: -0.002212, Z: -0.001697 (rad/s) 2022-03-16T19:08:02.687Z,1647457682.687 [AHRS_M2](INFO): YAW ERR: 0.182163 (deg), TEMP: 27.827417 (degC) 2022-03-16T19:08:03.092Z,1647457683.092 [AHRS_M2](INFO): PITCH: -0.395799, ROLL: -3.752017, YAW: 213.568213 (deg) 2022-03-16T19:08:03.093Z,1647457683.093 [AHRS_M2](INFO): Mag: X: -15.594398, Y: 5.746124, Z: 51.357678 (uT) 2022-03-16T19:08:03.094Z,1647457683.094 [AHRS_M2](INFO): Accel: X: 0.075963, Y: -0.644754, Z: 9.839053 (m/s2) 2022-03-16T19:08:03.095Z,1647457683.095 [AHRS_M2](INFO): Ang.Vel: X: 0.006396, Y: -0.003016, Z: -0.002228 (rad/s) 2022-03-16T19:08:03.095Z,1647457683.095 [AHRS_M2](INFO): YAW ERR: 0.182231 (deg), TEMP: 27.830477 (degC) 2022-03-16T19:08:03.494Z,1647457683.494 [AHRS_M2](INFO): PITCH: -0.382524, ROLL: -3.746711, YAW: 213.578158 (deg) 2022-03-16T19:08:03.494Z,1647457683.494 [AHRS_M2](INFO): Mag: X: -15.595488, Y: 5.724386, Z: 51.333270 (uT) 2022-03-16T19:08:03.495Z,1647457683.495 [AHRS_M2](INFO): Accel: X: 0.068103, Y: -0.624115, Z: 9.858292 (m/s2) 2022-03-16T19:08:03.496Z,1647457683.496 [AHRS_M2](INFO): Ang.Vel: X: 0.005859, Y: -0.002219, Z: -0.001694 (rad/s) 2022-03-16T19:08:03.496Z,1647457683.496 [AHRS_M2](INFO): YAW ERR: 0.182263 (deg), TEMP: 27.815178 (degC) 2022-03-16T19:08:03.896Z,1647457683.896 [AHRS_M2](INFO): PITCH: -0.384570, ROLL: -3.744142, YAW: 213.573527 (deg) 2022-03-16T19:08:03.897Z,1647457683.897 [AHRS_M2](INFO): Mag: X: -15.661786, Y: 5.740839, Z: 51.290594 (uT) 2022-03-16T19:08:03.897Z,1647457683.897 [AHRS_M2](INFO): Accel: X: 0.064123, Y: -0.640986, Z: 9.850662 (m/s2) 2022-03-16T19:08:03.898Z,1647457683.898 [AHRS_M2](INFO): Ang.Vel: X: 0.004796, Y: -0.001960, Z: -0.002492 (rad/s) 2022-03-16T19:08:03.899Z,1647457683.899 [AHRS_M2](INFO): YAW ERR: 0.182146 (deg), TEMP: 27.812119 (degC) 2022-03-16T19:08:04.304Z,1647457684.304 [AHRS_M2](INFO): PITCH: -0.384978, ROLL: -3.747410, YAW: 213.585561 (deg) 2022-03-16T19:08:04.305Z,1647457684.305 [AHRS_M2](INFO): Mag: X: -15.674783, Y: 5.737056, Z: 51.318115 (uT) 2022-03-16T19:08:04.306Z,1647457684.306 [AHRS_M2](INFO): Accel: X: 0.074799, Y: -0.644605, Z: 9.846819 (m/s2) 2022-03-16T19:08:04.307Z,1647457684.307 [AHRS_M2](INFO): Ang.Vel: X: 0.006393, Y: -0.002220, Z: -0.003025 (rad/s) 2022-03-16T19:08:04.307Z,1647457684.307 [AHRS_M2](INFO): YAW ERR: 0.182196 (deg), TEMP: 27.818237 (degC) 2022-03-16T19:08:04.553Z,1647457684.553 [CommandExec](IMPORTANT): got command configSet AHRS_M2.verbosity 0 count 2022-03-16T19:08:25.318Z,1647457705.318 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-03-16T19:08:25.319Z,1647457705.319 [NAL9602] Data Fault, FailCount= 1 2022-03-16T19:08:25.319Z,1647457705.319 [NAL9602](ERROR): Data Fault 2022-03-16T19:08:25.371Z,1647457705.371 [CBIT](ERROR): Data Fault in component: NAL9602 2022-03-16T19:08:25.716Z,1647457705.716 [NAL9602](INFO): Powering down 2022-03-16T19:08:26.559Z,1647457706.559 [CBIT](INFO): Clearing failed state for component NAL9602 2022-03-16T19:08:26.559Z,1647457706.559 [NAL9602] No Fault, FailCount= 1 2022-03-16T19:08:28.524Z,1647457708.524 [CommandLine](INFO): End of History 2022-03-16T19:08:28.589Z,1647457708.589 [CommandLine](INFO): End of History 2022-03-16T19:08:28.591Z,1647457708.591 [CommandLine](INFO): End of History 2022-03-16T19:08:28.592Z,1647457708.592 [CommandLine](INFO): End of History 2022-03-16T19:08:28.598Z,1647457708.598 [CommandLine](INFO): End of History 2022-03-16T19:08:28.599Z,1647457708.599 [CommandLine](INFO): End of History 2022-03-16T19:08:28.600Z,1647457708.600 [CommandLine](INFO): End of History 2022-03-16T19:08:28.610Z,1647457708.610 [CommandLine](INFO): End of History 2022-03-16T19:08:28.611Z,1647457708.611 [CommandLine](INFO): End of History 2022-03-16T19:08:28.612Z,1647457708.612 [CommandLine](INFO): End of History 2022-03-16T19:08:28.626Z,1647457708.626 [CommandLine](INFO): End of History 2022-03-16T19:08:28.627Z,1647457708.627 [CommandLine](INFO): End of History 2022-03-16T19:08:28.629Z,1647457708.629 [CommandLine](INFO): End of History 2022-03-16T19:08:28.635Z,1647457708.635 [CommandLine](INFO): End of History 2022-03-16T19:08:28.637Z,1647457708.637 [CommandLine](INFO): End of History 2022-03-16T19:08:28.650Z,1647457708.650 [CommandLine](INFO): End of History 2022-03-16T19:08:28.651Z,1647457708.651 [CommandLine](INFO): End of History 2022-03-16T19:08:28.653Z,1647457708.653 [CommandLine](INFO): End of History 2022-03-16T19:08:28.662Z,1647457708.662 [CommandLine](INFO): End of History 2022-03-16T19:08:28.663Z,1647457708.663 [CommandLine](INFO): End of History 2022-03-16T19:08:28.665Z,1647457708.665 [CommandLine](INFO): End of History 2022-03-16T19:08:56.031Z,1647457736.031 [NAL9602](INFO): Powering up NAL9602 2022-03-16T19:09:06.928Z,1647457746.928 [NAL9602](INFO): NAL9602 initialized 2022-03-16T19:09:44.926Z,1647457784.926 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2022-03-16T19:09:44.926Z,1647457784.926 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:09:44.927Z,1647457784.927 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2022-03-16T19:09:44.927Z,1647457784.927 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:09:44.982Z,1647457784.982 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:09:44.982Z,1647457784.982 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:09:45.325Z,1647457785.325 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:09:45.325Z,1647457785.325 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2022-03-16T19:09:45.325Z,1647457785.325 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:09:45.325Z,1647457785.325 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2022-03-16T19:09:45.725Z,1647457785.725 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:10:49.826Z,1647457849.826 [CommandLine](INFO): End of History 2022-03-16T19:10:49.827Z,1647457849.827 [CommandLine](INFO): End of History 2022-03-16T19:10:49.828Z,1647457849.828 [CommandLine](INFO): End of History 2022-03-16T19:10:49.839Z,1647457849.839 [CommandLine](INFO): End of History 2022-03-16T19:10:49.844Z,1647457849.844 [CommandLine](INFO): End of History 2022-03-16T19:10:49.850Z,1647457849.850 [CommandLine](INFO): End of History 2022-03-16T19:10:49.851Z,1647457849.851 [CommandLine](INFO): End of History 2022-03-16T19:10:49.852Z,1647457849.852 [CommandLine](INFO): End of History 2022-03-16T19:10:49.886Z,1647457849.886 [CommandLine](INFO): End of History 2022-03-16T19:10:49.887Z,1647457849.887 [CommandLine](INFO): End of History 2022-03-16T19:10:49.888Z,1647457849.888 [CommandLine](INFO): End of History 2022-03-16T19:10:49.894Z,1647457849.894 [CommandLine](INFO): End of History 2022-03-16T19:10:49.895Z,1647457849.895 [CommandLine](INFO): End of History 2022-03-16T19:10:49.896Z,1647457849.896 [CommandLine](INFO): End of History 2022-03-16T19:10:49.906Z,1647457849.906 [CommandLine](INFO): End of History 2022-03-16T19:10:49.907Z,1647457849.907 [CommandLine](INFO): End of History 2022-03-16T19:10:49.908Z,1647457849.908 [CommandLine](INFO): End of History 2022-03-16T19:10:49.918Z,1647457849.918 [CommandLine](INFO): End of History 2022-03-16T19:11:57.447Z,1647457917.447 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-16T19:11:57.447Z,1647457917.447 [Default:CheckIn:C.Wait] Stopped 2022-03-16T19:11:57.447Z,1647457917.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-16T19:11:57.447Z,1647457917.447 [Default:CheckIn:D] Running Loop=1 2022-03-16T19:11:57.839Z,1647457917.839 [Default:CheckIn:D] Stopped 2022-03-16T19:11:57.839Z,1647457917.839 [Default:CheckIn:E] Running Loop=1 2022-03-16T19:11:58.242Z,1647457918.242 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.699299 min 2022-03-16T19:11:58.242Z,1647457918.242 [Default:CheckIn:E] Stopped 2022-03-16T19:11:58.243Z,1647457918.243 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-16T19:11:58.243Z,1647457918.243 [Default:CheckIn] Stopped 2022-03-16T19:11:58.243Z,1647457918.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-16T19:11:58.243Z,1647457918.243 [Default:CheckIn](INFO): Running loop #2 2022-03-16T19:11:58.243Z,1647457918.243 [Default:CheckIn] Running Loop=2 2022-03-16T19:11:58.243Z,1647457918.243 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-16T19:11:58.243Z,1647457918.243 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-16T19:12:45.926Z,1647457965.926 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2022-03-16T19:12:45.926Z,1647457965.926 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:12:45.927Z,1647457965.927 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2022-03-16T19:12:45.927Z,1647457965.927 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:12:45.937Z,1647457965.937 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:12:45.938Z,1647457965.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:12:46.337Z,1647457966.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:12:46.338Z,1647457966.338 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2022-03-16T19:12:46.338Z,1647457966.338 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:12:46.338Z,1647457966.338 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2022-03-16T19:12:46.719Z,1647457966.719 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:15:46.917Z,1647458146.917 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2022-03-16T19:15:46.917Z,1647458146.917 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:15:46.918Z,1647458146.918 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2022-03-16T19:15:46.918Z,1647458146.918 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:15:46.929Z,1647458146.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:15:46.929Z,1647458146.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:15:47.315Z,1647458147.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:15:47.315Z,1647458147.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2022-03-16T19:15:47.316Z,1647458147.316 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:15:47.316Z,1647458147.316 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2022-03-16T19:15:47.706Z,1647458147.706 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:16:58.411Z,1647458218.411 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-16T19:11:58.2Z 2022-03-16T19:16:58.412Z,1647458218.412 [Default:CheckIn:Read_GPS] Stopped 2022-03-16T19:16:58.412Z,1647458218.412 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-16T19:17:05.181Z,1647458225.181 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220316T185731/Courier0007.lzma 2022-03-16T19:17:06.184Z,1647458226.184 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Courier0007.lzma.bak 2022-03-16T19:17:06.184Z,1647458226.184 [DataOverHttps](INFO): SBD MOMSN=16574655 2022-03-16T19:17:21.785Z,1647458241.785 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20220316T185731/Express0008.lzma 2022-03-16T19:17:22.787Z,1647458242.787 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Express0008.lzma.bak 2022-03-16T19:17:22.787Z,1647458242.787 [DataOverHttps](INFO): SBD MOMSN=16574657 2022-03-16T19:17:24.287Z,1647458244.287 [Default:CheckIn:Read_Iridium] Stopped 2022-03-16T19:17:24.287Z,1647458244.287 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-16T19:17:24.287Z,1647458244.287 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-16T19:18:47.892Z,1647458327.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2022-03-16T19:18:47.892Z,1647458327.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:18:47.893Z,1647458327.893 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2022-03-16T19:18:47.893Z,1647458327.893 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:18:47.904Z,1647458327.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:18:47.904Z,1647458327.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:18:48.318Z,1647458328.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:18:48.318Z,1647458328.318 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2022-03-16T19:18:48.318Z,1647458328.318 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:18:48.318Z,1647458328.318 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2022-03-16T19:18:48.698Z,1647458328.698 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:19:10.508Z,1647458350.508 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-03-16T19:19:10.508Z,1647458350.508 [NAL9602] Data Fault, FailCount= 2 2022-03-16T19:19:10.508Z,1647458350.508 [NAL9602](ERROR): Data Fault 2022-03-16T19:19:10.525Z,1647458350.525 [CBIT](ERROR): Data Fault in component: NAL9602 2022-03-16T19:19:10.919Z,1647458350.919 [NAL9602](INFO): Powering down 2022-03-16T19:19:11.758Z,1647458351.758 [CBIT](INFO): Clearing failed state for component NAL9602 2022-03-16T19:19:11.758Z,1647458351.758 [NAL9602] No Fault, FailCount= 2 2022-03-16T19:19:41.212Z,1647458381.212 [NAL9602](INFO): Powering up NAL9602 2022-03-16T19:19:52.123Z,1647458392.123 [NAL9602](INFO): NAL9602 initialized 2022-03-16T19:21:48.885Z,1647458508.885 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2022-03-16T19:21:48.885Z,1647458508.885 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:21:48.887Z,1647458508.887 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2022-03-16T19:21:48.887Z,1647458508.887 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:21:48.919Z,1647458508.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:21:48.919Z,1647458508.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:21:49.303Z,1647458509.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:21:49.303Z,1647458509.303 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2022-03-16T19:21:49.303Z,1647458509.303 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:21:49.303Z,1647458509.303 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2022-03-16T19:21:49.696Z,1647458509.696 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:22:24.879Z,1647458544.879 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-16T19:22:24.879Z,1647458544.879 [Default:CheckIn:C.Wait] Stopped 2022-03-16T19:22:24.880Z,1647458544.880 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-16T19:22:24.880Z,1647458544.880 [Default:CheckIn:D] Running Loop=1 2022-03-16T19:22:25.256Z,1647458545.256 [Default:CheckIn:D] Stopped 2022-03-16T19:22:25.256Z,1647458545.256 [Default:CheckIn:E] Running Loop=1 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.156256 min 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn:E] Stopped 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn] Stopped 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn](INFO): Running loop #3 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn] Running Loop=3 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-16T19:22:25.663Z,1647458545.663 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-16T19:24:49.890Z,1647458689.890 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2022-03-16T19:24:49.890Z,1647458689.890 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:24:49.891Z,1647458689.891 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2022-03-16T19:24:49.891Z,1647458689.891 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:24:49.901Z,1647458689.901 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:24:49.901Z,1647458689.901 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:24:50.315Z,1647458690.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:24:50.315Z,1647458690.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2022-03-16T19:24:50.315Z,1647458690.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:24:50.315Z,1647458690.315 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2022-03-16T19:24:50.721Z,1647458690.721 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:27:25.848Z,1647458845.848 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-16T19:22:25.7Z 2022-03-16T19:27:25.848Z,1647458845.848 [Default:CheckIn:Read_GPS] Stopped 2022-03-16T19:27:25.848Z,1647458845.848 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-16T19:27:32.424Z,1647458852.424 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220316T185731/Courier0010.lzma 2022-03-16T19:27:33.427Z,1647458853.427 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Courier0010.lzma.bak 2022-03-16T19:27:33.427Z,1647458853.427 [DataOverHttps](INFO): SBD MOMSN=16574664 2022-03-16T19:27:49.013Z,1647458869.013 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220316T185731/Express0011.lzma 2022-03-16T19:27:50.015Z,1647458870.015 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Express0011.lzma.bak 2022-03-16T19:27:50.015Z,1647458870.015 [DataOverHttps](INFO): SBD MOMSN=16574668 2022-03-16T19:27:50.881Z,1647458870.881 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2022-03-16T19:27:50.881Z,1647458870.881 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:27:50.882Z,1647458870.882 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10 2022-03-16T19:27:50.882Z,1647458870.882 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:27:50.893Z,1647458870.893 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:27:50.893Z,1647458870.893 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:27:51.323Z,1647458871.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:27:51.324Z,1647458871.324 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2022-03-16T19:27:51.324Z,1647458871.324 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:27:51.324Z,1647458871.324 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10 2022-03-16T19:27:51.705Z,1647458871.705 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:27:51.706Z,1647458871.706 [Default:CheckIn:Read_Iridium] Stopped 2022-03-16T19:27:51.706Z,1647458871.706 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-16T19:27:51.707Z,1647458871.707 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-16T19:29:53.286Z,1647458993.286 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-03-16T19:29:53.286Z,1647458993.286 [NAL9602] Data Fault, FailCount= 3 2022-03-16T19:29:53.286Z,1647458993.286 [NAL9602](ERROR): Data Fault 2022-03-16T19:29:53.302Z,1647458993.302 [CBIT](ERROR): Data Fault in component: NAL9602 2022-03-16T19:29:53.687Z,1647458993.687 [NAL9602](INFO): Powering down 2022-03-16T19:29:54.506Z,1647458994.506 [CBIT](INFO): Clearing failed state for component NAL9602 2022-03-16T19:29:54.506Z,1647458994.506 [NAL9602] No Fault, FailCount= 3 2022-03-16T19:30:23.980Z,1647459023.980 [NAL9602](INFO): Powering up NAL9602 2022-03-16T19:30:34.900Z,1647459034.900 [NAL9602](INFO): NAL9602 initialized 2022-03-16T19:30:51.897Z,1647459051.897 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2022-03-16T19:30:51.897Z,1647459051.897 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:30:51.899Z,1647459051.899 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11 2022-03-16T19:30:51.899Z,1647459051.899 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:30:51.917Z,1647459051.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:30:51.917Z,1647459051.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:30:52.287Z,1647459052.287 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:30:52.288Z,1647459052.288 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2022-03-16T19:30:52.288Z,1647459052.288 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:30:52.288Z,1647459052.288 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11 2022-03-16T19:30:52.681Z,1647459052.681 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:32:52.255Z,1647459172.255 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-16T19:32:52.255Z,1647459172.255 [Default:CheckIn:C.Wait] Stopped 2022-03-16T19:32:52.255Z,1647459172.255 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-16T19:32:52.255Z,1647459172.255 [Default:CheckIn:D] Running Loop=1 2022-03-16T19:32:52.659Z,1647459172.659 [Default:CheckIn:D] Stopped 2022-03-16T19:32:52.659Z,1647459172.659 [Default:CheckIn:E] Running Loop=1 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.612968 min 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn:E] Stopped 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn] Stopped 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn](INFO): Running loop #4 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn] Running Loop=4 2022-03-16T19:32:53.067Z,1647459173.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-16T19:32:53.068Z,1647459173.068 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-16T19:33:52.880Z,1647459232.880 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2022-03-16T19:33:52.880Z,1647459232.880 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:33:52.881Z,1647459232.881 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12 2022-03-16T19:33:52.881Z,1647459232.881 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:33:52.891Z,1647459232.891 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:33:52.892Z,1647459232.892 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:33:53.266Z,1647459233.266 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:33:53.266Z,1647459233.266 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2022-03-16T19:33:53.266Z,1647459233.266 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:33:53.266Z,1647459233.266 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12 2022-03-16T19:33:53.668Z,1647459233.668 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:36:53.866Z,1647459413.866 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2022-03-16T19:36:53.866Z,1647459413.866 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:36:53.867Z,1647459413.867 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13 2022-03-16T19:36:53.867Z,1647459413.867 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:36:53.878Z,1647459413.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:36:53.878Z,1647459413.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:36:54.278Z,1647459414.278 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:36:54.278Z,1647459414.278 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2022-03-16T19:36:54.278Z,1647459414.278 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:36:54.278Z,1647459414.278 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13 2022-03-16T19:36:54.649Z,1647459414.649 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:37:53.238Z,1647459473.238 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-16T19:32:53.1Z 2022-03-16T19:37:53.238Z,1647459473.238 [Default:CheckIn:Read_GPS] Stopped 2022-03-16T19:37:53.238Z,1647459473.238 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-16T19:37:59.461Z,1647459479.461 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220316T185731/Courier0013.lzma 2022-03-16T19:38:00.463Z,1647459480.463 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Courier0013.lzma.bak 2022-03-16T19:38:00.463Z,1647459480.463 [DataOverHttps](INFO): SBD MOMSN=16574673 2022-03-16T19:38:16.169Z,1647459496.169 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220316T185731/Express0014.lzma 2022-03-16T19:38:17.171Z,1647459497.171 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Express0014.lzma.bak 2022-03-16T19:38:17.171Z,1647459497.171 [DataOverHttps](INFO): SBD MOMSN=16574675 2022-03-16T19:38:18.694Z,1647459498.694 [Default:CheckIn:Read_Iridium] Stopped 2022-03-16T19:38:18.694Z,1647459498.694 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-16T19:38:18.694Z,1647459498.694 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-16T19:39:54.850Z,1647459594.850 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2022-03-16T19:39:54.850Z,1647459594.850 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:39:54.851Z,1647459594.851 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14 2022-03-16T19:39:54.851Z,1647459594.851 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:39:54.890Z,1647459594.890 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:39:54.890Z,1647459594.890 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:39:55.267Z,1647459595.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:39:55.267Z,1647459595.267 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2022-03-16T19:39:55.267Z,1647459595.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:39:55.267Z,1647459595.267 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14 2022-03-16T19:39:55.647Z,1647459595.647 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:40:37.652Z,1647459637.652 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-03-16T19:40:37.652Z,1647459637.652 [NAL9602] Data Fault, FailCount= 4 2022-03-16T19:40:37.652Z,1647459637.652 [NAL9602](ERROR): Data Fault 2022-03-16T19:40:37.708Z,1647459637.708 [CBIT](ERROR): Data Fault in component: NAL9602 2022-03-16T19:40:38.056Z,1647459638.056 [NAL9602](INFO): Powering down 2022-03-16T19:40:38.965Z,1647459638.965 [CBIT](INFO): Clearing failed state for component NAL9602 2022-03-16T19:40:38.966Z,1647459638.966 [NAL9602] No Fault, FailCount= 4 2022-03-16T19:41:08.360Z,1647459668.360 [NAL9602](INFO): Powering up NAL9602 2022-03-16T19:41:19.283Z,1647459679.283 [NAL9602](INFO): NAL9602 initialized 2022-03-16T19:42:55.843Z,1647459775.843 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2022-03-16T19:42:55.843Z,1647459775.843 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:42:55.844Z,1647459775.844 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15 2022-03-16T19:42:55.844Z,1647459775.844 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:42:55.854Z,1647459775.854 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:42:55.854Z,1647459775.854 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:42:56.274Z,1647459776.274 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:42:56.274Z,1647459776.274 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2022-03-16T19:42:56.275Z,1647459776.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:42:56.275Z,1647459776.275 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15 2022-03-16T19:42:56.640Z,1647459776.640 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:43:19.292Z,1647459799.292 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-16T19:43:19.292Z,1647459799.292 [Default:CheckIn:C.Wait] Stopped 2022-03-16T19:43:19.292Z,1647459799.292 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-16T19:43:19.292Z,1647459799.292 [Default:CheckIn:D] Running Loop=1 2022-03-16T19:43:19.680Z,1647459799.680 [Default:CheckIn:D] Stopped 2022-03-16T19:43:19.681Z,1647459799.681 [Default:CheckIn:E] Running Loop=1 2022-03-16T19:43:20.099Z,1647459800.099 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.063330 min 2022-03-16T19:43:20.099Z,1647459800.099 [Default:CheckIn:E] Stopped 2022-03-16T19:43:20.099Z,1647459800.099 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-16T19:43:20.100Z,1647459800.100 [Default:CheckIn] Stopped 2022-03-16T19:43:20.100Z,1647459800.100 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-16T19:43:20.100Z,1647459800.100 [Default:CheckIn](INFO): Running loop #5 2022-03-16T19:43:20.100Z,1647459800.100 [Default:CheckIn] Running Loop=5 2022-03-16T19:43:20.100Z,1647459800.100 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-16T19:43:20.100Z,1647459800.100 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-16T19:45:56.835Z,1647459956.835 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2022-03-16T19:45:56.835Z,1647459956.835 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:45:56.836Z,1647459956.836 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16 2022-03-16T19:45:56.836Z,1647459956.836 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:45:56.846Z,1647459956.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:45:56.846Z,1647459956.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:45:57.259Z,1647459957.259 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:45:57.259Z,1647459957.259 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2022-03-16T19:45:57.260Z,1647459957.260 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:45:57.260Z,1647459957.260 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16 2022-03-16T19:45:57.630Z,1647459957.630 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:48:20.276Z,1647460100.276 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-16T19:43:20.1Z 2022-03-16T19:48:20.276Z,1647460100.276 [Default:CheckIn:Read_GPS] Stopped 2022-03-16T19:48:20.276Z,1647460100.276 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-16T19:48:27.061Z,1647460107.061 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220316T185731/Courier0016.lzma 2022-03-16T19:48:28.063Z,1647460108.063 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Courier0016.lzma.bak 2022-03-16T19:48:28.063Z,1647460108.063 [DataOverHttps](INFO): SBD MOMSN=16574679 2022-03-16T19:48:43.705Z,1647460123.705 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20220316T185731/Express0017.lzma 2022-03-16T19:48:44.707Z,1647460124.707 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Express0017.lzma.bak 2022-03-16T19:48:44.707Z,1647460124.707 [DataOverHttps](INFO): SBD MOMSN=16574681 2022-03-16T19:48:46.123Z,1647460126.123 [Default:CheckIn:Read_Iridium] Stopped 2022-03-16T19:48:46.123Z,1647460126.123 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-16T19:48:46.124Z,1647460126.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-16T19:48:57.849Z,1647460137.849 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2022-03-16T19:48:57.849Z,1647460137.849 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:48:57.850Z,1647460137.850 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17 2022-03-16T19:48:57.850Z,1647460137.850 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:48:57.861Z,1647460137.861 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:48:57.861Z,1647460137.861 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:48:58.267Z,1647460138.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:48:58.267Z,1647460138.267 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2022-03-16T19:48:58.267Z,1647460138.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:48:58.268Z,1647460138.268 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17 2022-03-16T19:48:58.619Z,1647460138.619 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:51:22.483Z,1647460282.483 [NAL9602](FAULT): GPS failed to acquire within timeout. 2022-03-16T19:51:22.483Z,1647460282.483 [NAL9602] Data Fault, FailCount= 5 2022-03-16T19:51:22.483Z,1647460282.483 [NAL9602](ERROR): Data Fault 2022-03-16T19:51:22.500Z,1647460282.500 [CBIT](ERROR): Data Fault in component: NAL9602 2022-03-16T19:51:22.501Z,1647460282.501 [CBIT](CRITICAL): Data Fault in component: NAL9602 2022-03-16T19:51:22.899Z,1647460282.899 [NAL9602](INFO): Powering down 2022-03-16T19:51:22.914Z,1647460282.914 [CommandExec](FAULT): Scheduling is paused 2022-03-16T19:51:22.914Z,1647460282.914 [CBIT](INFO): Critical error at 20220316T195122 2022-03-16T19:51:22.915Z,1647460282.915 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2022-03-16T19:51:58.851Z,1647460318.851 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2022-03-16T19:51:58.851Z,1647460318.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:51:58.853Z,1647460318.853 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18 2022-03-16T19:51:58.853Z,1647460318.853 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:51:58.863Z,1647460318.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:51:58.863Z,1647460318.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:51:59.276Z,1647460319.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:51:59.276Z,1647460319.276 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2022-03-16T19:51:59.276Z,1647460319.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:51:59.276Z,1647460319.276 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18 2022-03-16T19:51:59.659Z,1647460319.659 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:53:22.506Z,1647460402.506 [CBIT](INFO): Clearing failed state for component NAL9602 2022-03-16T19:53:22.506Z,1647460402.506 [NAL9602] No Fault, FailCount= 5 2022-03-16T19:53:22.879Z,1647460402.879 [NAL9602](INFO): Powering up NAL9602 2022-03-16T19:53:33.787Z,1647460413.787 [NAL9602](INFO): NAL9602 initialized 2022-03-16T19:53:46.747Z,1647460426.747 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-16T19:53:46.747Z,1647460426.747 [Default:CheckIn:C.Wait] Stopped 2022-03-16T19:53:46.747Z,1647460426.747 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-16T19:53:46.747Z,1647460426.747 [Default:CheckIn:D] Running Loop=1 2022-03-16T19:53:47.156Z,1647460427.156 [Default:CheckIn:D] Stopped 2022-03-16T19:53:47.156Z,1647460427.156 [Default:CheckIn:E] Running Loop=1 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.521257 min 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn:E] Stopped 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn] Stopped 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn](INFO): Running loop #6 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn] Running Loop=6 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-03-16T19:53:47.548Z,1647460427.548 [Default:CheckIn:Read_GPS] Running Loop=1 2022-03-16T19:54:59.861Z,1647460499.861 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2022-03-16T19:54:59.861Z,1647460499.861 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:54:59.862Z,1647460499.862 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19 2022-03-16T19:54:59.862Z,1647460499.862 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:54:59.902Z,1647460499.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:54:59.902Z,1647460499.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:55:00.279Z,1647460500.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:55:00.279Z,1647460500.279 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2022-03-16T19:55:00.279Z,1647460500.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:55:00.279Z,1647460500.279 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19 2022-03-16T19:55:00.662Z,1647460500.662 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:58:00.847Z,1647460680.847 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2022-03-16T19:58:00.847Z,1647460680.847 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T19:58:00.848Z,1647460680.848 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20 2022-03-16T19:58:00.848Z,1647460680.848 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T19:58:00.858Z,1647460680.858 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T19:58:00.858Z,1647460680.858 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T19:58:01.271Z,1647460681.271 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T19:58:01.272Z,1647460681.272 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2022-03-16T19:58:01.272Z,1647460681.272 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T19:58:01.272Z,1647460681.272 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20 2022-03-16T19:58:01.676Z,1647460681.676 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T19:58:47.723Z,1647460727.723 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-16T19:53:47.5Z 2022-03-16T19:58:47.723Z,1647460727.723 [Default:CheckIn:Read_GPS] Stopped 2022-03-16T19:58:47.723Z,1647460727.723 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-03-16T19:58:53.885Z,1647460733.885 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20220316T185731/Courier0019.lzma 2022-03-16T19:58:54.887Z,1647460734.887 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Courier0019.lzma.bak 2022-03-16T19:58:54.887Z,1647460734.887 [DataOverHttps](INFO): SBD MOMSN=16574696 2022-03-16T19:59:10.672Z,1647460750.672 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20220316T185731/Express0020.lzma 2022-03-16T19:59:11.491Z,1647460751.491 [DataOverHttps](INFO): Moved sent file to Logs/20220316T185731/Express0020.lzma.bak 2022-03-16T19:59:11.491Z,1647460751.491 [DataOverHttps](INFO): SBD MOMSN=16574699 2022-03-16T19:59:13.182Z,1647460753.182 [Default:CheckIn:Read_Iridium] Stopped 2022-03-16T19:59:13.182Z,1647460753.182 [Default:CheckIn:C.Wait] Running Loop=1 2022-03-16T19:59:13.182Z,1647460753.182 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-03-16T20:01:01.997Z,1647460861.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2022-03-16T20:01:01.997Z,1647460861.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T20:01:01.998Z,1647460861.998 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21 2022-03-16T20:01:01.998Z,1647460861.998 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T20:01:02.037Z,1647460862.037 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-03-16T20:01:02.041Z,1647460862.041 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2022-03-16T20:01:02.444Z,1647460862.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-03-16T20:01:02.444Z,1647460862.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2022-03-16T20:01:02.444Z,1647460862.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2022-03-16T20:01:02.444Z,1647460862.444 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21 2022-03-16T20:01:02.808Z,1647460862.808 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2022-03-16T20:01:58.057Z,1647460918.057 [CBIT](CRITICAL): Run-time exception #7 2022-03-16T20:01:58.057Z,1647460918.057 [CBIT] Software Fault, FailCount= 1 2022-03-16T20:01:58.057Z,1647460918.057 [CBIT](ERROR): Software Fault 2022-03-16T20:01:58.059Z,1647460918.059 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-03-16T20:01:58.060Z,1647460918.060 [SyncHandler](INFO): Protected caller Thread ID is 4419 2022-03-16T20:01:58.649Z,1647460918.649 [ElevatorServo](ERROR): getPosition uart error serial timeout 2022-03-16T20:01:58.650Z,1647460918.650 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2022-03-16T20:01:58.650Z,1647460918.650 [ElevatorServo] Communications Fault, FailCount= 1 2022-03-16T20:01:58.650Z,1647460918.650 [ElevatorServo](ERROR): Communications Fault 2022-03-16T20:01:58.961Z,1647460918.961 [MassServo](FAULT): Mass Shifter uart error: serial timeout 2022-03-16T20:01:58.961Z,1647460918.961 [MassServo] Communications Fault, FailCount= 1 2022-03-16T20:01:58.961Z,1647460918.961 [MassServo](ERROR): Communications Fault 2022-03-16T20:01:58.963Z,1647460918.963 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-03-16T20:01:58.963Z,1647460918.963 [CBIT](DEBUG): Powering off loads. 2022-03-16T20:01:58.984Z,1647460918.984 [CBIT](DEBUG): Disabling WDT. 2022-03-16T20:01:59.042Z,1647460919.042 [CBIT](DEBUG): Opening all GF detection circuits. 2022-03-16T20:01:59.205Z,1647460919.205 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-03-16T20:01:59.206Z,1647460919.206 [ElevatorServo](INFO): Powering down 2022-03-16T20:01:59.207Z,1647460919.207 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-03-16T20:01:59.207Z,1647460919.207 [MassServo](INFO): Powering down 2022-03-16T20:01:59.965Z,1647460919.965 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-03-16T20:01:59.966Z,1647460919.966 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-03-16T20:02:00.766Z,1647460920.766 [Radio_Surface](FAULT): LCB fault: Current Limiter Activated. 2022-03-16T20:02:00.766Z,1647460920.766 [Radio_Surface] Hardware Fault, FailCount= 1 2022-03-16T20:02:00.766Z,1647460920.766 [Radio_Surface](ERROR): Hardware Fault 2022-03-16T20:02:00.818Z,1647460920.818 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-03-16T20:02:01.170Z,1647460921.170 [Radio_Surface](INFO): Powering down 2022-03-16T20:02:01.262Z,1647460921.262 [DAT](FAULT): LCB fault: Current Limiter Activated. 2022-03-16T20:02:01.262Z,1647460921.262 [DAT] Hardware Fault, FailCount= 1 2022-03-16T20:02:01.262Z,1647460921.262 [DAT](ERROR): Hardware Fault 2022-03-16T20:02:01.263Z,1647460921.263 [DAT](INFO): Powering down 2022-03-16T20:02:01.559Z,1647460921.559 [NAL9602](FAULT): LCB fault: Current Limiter Activated. 2022-03-16T20:02:01.559Z,1647460921.559 [NAL9602] Hardware Fault, FailCount= 1 2022-03-16T20:02:01.559Z,1647460921.559 [NAL9602](ERROR): Hardware Fault 2022-03-16T20:02:01.573Z,1647460921.573 [Radio_Surface](FAULT): LCB fault: Current Limiter Activated. 2022-03-16T20:02:01.666Z,1647460921.666 [DAT](INFO): Powering down 2022-03-16T20:02:01.970Z,1647460921.970 [NAL9602](INFO): Powering down 2022-03-16T20:02:02.074Z,1647460922.074 [DAT](FAULT): LCB fault: Current Limiter Activated. 2022-03-16T20:02:02.363Z,1647460922.363 [NAL9602](FAULT): LCB fault: Current Limiter Activated. 2022-03-16T20:02:07.961Z,1647460927.961 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2022-03-16T20:02:07.961Z,1647460927.961 [AHRS_M2] Data Fault, FailCount= 1 2022-03-16T20:02:07.961Z,1647460927.961 [AHRS_M2](ERROR): Data Fault 2022-03-16T20:02:08.342Z,1647460928.342 [AHRS_M2](INFO): Powering down 2022-03-16T20:02:46.014Z,1647460966.014 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255211 2022-03-16T20:04:03.095Z,1647461043.095 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2022-03-16T20:04:03.095Z,1647461043.095 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-03-16T20:04:03.096Z,1647461043.096 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 22 2022-03-16T20:04:03.096Z,1647461043.096 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2022-03-16T20:04:13.602Z,1647461053.602 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-03-16T20:04:13.602Z,1647461053.602 [Default:CheckIn:C.Wait] Stopped 2022-03-16T20:04:13.602Z,1647461053.602 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-03-16T20