2021-11-05T21:58:56.249Z,1636149536.249 [Supervisor](DEBUG): Initializing supervisor. 2021-11-05T21:58:56.253Z,1636149536.253 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-05T21:58:56.254Z,1636149536.254 [SyncHandler](INFO): Protected caller Thread ID is 2695 2021-11-05T21:58:56.254Z,1636149536.254 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-11-05T21:58:56.255Z,1636149536.255 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-11-05T21:58:56.256Z,1636149536.256 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2696 2021-11-05T21:58:56.260Z,1636149536.260 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-11-05T21:58:56.278Z,1636149536.278 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2021-11-05T21:58:56.279Z,1636149536.279 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-11-05T21:58:56.279Z,1636149536.279 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2697 2021-11-05T21:58:56.283Z,1636149536.283 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-11-05T21:58:56.284Z,1636149536.284 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-11-05T21:58:56.285Z,1636149536.285 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2698 2021-11-05T21:58:56.287Z,1636149536.287 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-11-05T21:58:56.288Z,1636149536.288 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2021-11-05T21:58:56.288Z,1636149536.288 [logger ThreadHandler](INFO): Protected caller Thread ID is 2699 2021-11-05T21:58:56.292Z,1636149536.292 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-11-05T21:58:56.292Z,1636149536.292 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-11-05T21:58:56.294Z,1636149536.294 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-11-05T21:58:56.775Z,1636149536.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-11-05T21:58:56.776Z,1636149536.776 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-11-05T21:58:56.986Z,1636149536.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-11-05T21:58:56.987Z,1636149536.987 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-11-05T21:58:57.172Z,1636149537.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-11-05T21:58:57.173Z,1636149537.173 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-11-05T21:58:57.279Z,1636149537.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-11-05T21:58:57.280Z,1636149537.280 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-11-05T21:58:57.633Z,1636149537.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-11-05T21:58:57.634Z,1636149537.634 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-11-05T21:58:57.976Z,1636149537.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-11-05T21:58:57.977Z,1636149537.977 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-11-05T21:58:58.307Z,1636149538.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-11-05T21:58:58.307Z,1636149538.307 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-11-05T21:58:58.464Z,1636149538.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-11-05T21:58:58.464Z,1636149538.464 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-11-05T21:58:58.996Z,1636149538.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-11-05T21:58:58.997Z,1636149538.997 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-11-05T21:58:59.348Z,1636149539.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-11-05T21:58:59.349Z,1636149539.349 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-11-05T21:58:59.431Z,1636149539.431 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-11-05T21:58:59.632Z,1636149539.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-11-05T21:58:59.633Z,1636149539.633 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-11-05T21:58:59.760Z,1636149539.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-11-05T21:58:59.760Z,1636149539.760 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-11-05T21:59:00.407Z,1636149540.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-11-05T21:59:00.407Z,1636149540.407 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-11-05T21:59:00.487Z,1636149540.487 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-11-05T21:59:00.730Z,1636149540.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-11-05T21:59:00.732Z,1636149540.732 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2021-11-05T21:59:00.733Z,1636149540.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2021-11-05T21:59:00.903Z,1636149540.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2021-11-05T21:59:00.996Z,1636149540.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2021-11-05T21:59:01.107Z,1636149541.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2021-11-05T21:59:01.207Z,1636149541.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2021-11-05T21:59:01.290Z,1636149541.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2021-11-05T21:59:01.376Z,1636149541.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2021-11-05T21:59:01.488Z,1636149541.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2021-11-05T21:59:01.587Z,1636149541.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2021-11-05T21:59:01.734Z,1636149541.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2021-11-05T21:59:01.990Z,1636149541.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-11-05T21:59:01.991Z,1636149541.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2021-11-05T21:59:02.086Z,1636149542.086 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2021-11-05T21:59:02.086Z,1636149542.086 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-11-05T21:59:02.100Z,1636149542.100 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-11-05T21:59:02.423Z,1636149542.423 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-11-05T21:59:02.423Z,1636149542.423 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-11-05T21:59:02.504Z,1636149542.504 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-11-05T21:59:02.504Z,1636149542.504 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-11-05T21:59:02.537Z,1636149542.537 [DeadReckonUsingSpeedCalculator] Loaded 2021-11-05T21:59:02.537Z,1636149542.537 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2021-11-05T21:59:02.551Z,1636149542.551 [NavChart] Loaded 2021-11-05T21:59:02.551Z,1636149542.551 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-11-05T21:59:02.556Z,1636149542.556 [UniversalFixResidualReporter] Loaded 2021-11-05T21:59:02.557Z,1636149542.557 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-11-05T21:59:02.557Z,1636149542.557 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-11-05T21:59:02.558Z,1636149542.558 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-11-05T21:59:02.568Z,1636149542.568 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-11-05T21:59:02.569Z,1636149542.569 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-11-05T21:59:02.764Z,1636149542.764 [CTD_Seabird] Loaded 2021-11-05T21:59:02.764Z,1636149542.764 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-11-05T21:59:02.765Z,1636149542.765 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406514E0 2021-11-05T21:59:02.766Z,1636149542.766 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2781 2021-11-05T21:59:02.785Z,1636149542.785 [PAR_Licor] Loaded 2021-11-05T21:59:02.785Z,1636149542.785 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-11-05T21:59:02.817Z,1636149542.817 [WetLabsBB2FL] Loaded 2021-11-05T21:59:02.817Z,1636149542.817 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-11-05T21:59:02.818Z,1636149542.818 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406814E0 2021-11-05T21:59:02.818Z,1636149542.818 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2782 2021-11-05T21:59:02.819Z,1636149542.819 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-11-05T21:59:02.820Z,1636149542.820 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-11-05T21:59:02.875Z,1636149542.875 [VerticalControl](DEBUG): Construct VerticalControl. 2021-11-05T21:59:02.936Z,1636149542.936 [VerticalControl] Loaded 2021-11-05T21:59:02.936Z,1636149542.936 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-11-05T21:59:02.939Z,1636149542.939 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-11-05T21:59:02.978Z,1636149542.978 [HorizontalControl] Loaded 2021-11-05T21:59:02.978Z,1636149542.978 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-11-05T21:59:02.981Z,1636149542.981 [SpeedControl](DEBUG): Construct SpeedControl. 2021-11-05T21:59:02.984Z,1636149542.984 [SpeedControl] Loaded 2021-11-05T21:59:02.984Z,1636149542.984 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-11-05T21:59:02.987Z,1636149542.987 [LoopControl](DEBUG): Construct LoopControl. 2021-11-05T21:59:02.987Z,1636149542.987 [LoopControl] Loaded 2021-11-05T21:59:02.987Z,1636149542.987 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-11-05T21:59:02.988Z,1636149542.988 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-11-05T21:59:02.988Z,1636149542.988 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-11-05T21:59:03.104Z,1636149543.104 [DepthRateCalculator] Loaded 2021-11-05T21:59:03.104Z,1636149543.104 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-11-05T21:59:03.109Z,1636149543.109 [PitchRateCalculator] Loaded 2021-11-05T21:59:03.109Z,1636149543.109 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-11-05T21:59:03.119Z,1636149543.119 [SpeedCalculator] Loaded 2021-11-05T21:59:03.119Z,1636149543.119 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-11-05T21:59:03.132Z,1636149543.132 [TempGradientCalculator] Loaded 2021-11-05T21:59:03.133Z,1636149543.133 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-11-05T21:59:03.137Z,1636149543.137 [YawRateCalculator] Loaded 2021-11-05T21:59:03.137Z,1636149543.137 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-11-05T21:59:03.232Z,1636149543.232 [ElevatorOffsetCalculator] Loaded 2021-11-05T21:59:03.232Z,1636149543.232 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-11-05T21:59:03.233Z,1636149543.233 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-11-05T21:59:03.298Z,1636149543.298 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-11-05T21:59:03.526Z,1636149543.526 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-11-05T21:59:03.526Z,1636149543.526 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-11-05T21:59:03.588Z,1636149543.588 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-11-05T21:59:03.588Z,1636149543.588 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-11-05T21:59:03.716Z,1636149543.716 [BuoyancyServo] Loaded 2021-11-05T21:59:03.716Z,1636149543.716 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-11-05T21:59:03.736Z,1636149543.736 [ElevatorServo] Loaded 2021-11-05T21:59:03.736Z,1636149543.736 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-11-05T21:59:03.755Z,1636149543.755 [MassServo] Loaded 2021-11-05T21:59:03.755Z,1636149543.755 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-11-05T21:59:03.773Z,1636149543.773 [RudderServo] Loaded 2021-11-05T21:59:03.774Z,1636149543.774 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-11-05T21:59:03.788Z,1636149543.788 [ThrusterHE] Loaded 2021-11-05T21:59:03.788Z,1636149543.788 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2021-11-05T21:59:03.788Z,1636149543.788 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-11-05T21:59:03.789Z,1636149543.789 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-11-05T21:59:03.894Z,1636149543.894 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-11-05T21:59:03.894Z,1636149543.894 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-11-05T21:59:04.271Z,1636149544.271 [AHRS_M2] Loaded 2021-11-05T21:59:04.271Z,1636149544.271 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-11-05T21:59:04.301Z,1636149544.301 [BackseatComponent] Loaded 2021-11-05T21:59:04.301Z,1636149544.301 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2021-11-05T21:59:04.302Z,1636149544.302 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0 2021-11-05T21:59:04.303Z,1636149544.303 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2783 2021-11-05T21:59:04.305Z,1636149544.305 [LcmUniversalReporter] Loaded 2021-11-05T21:59:04.306Z,1636149544.306 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2021-11-05T21:59:05.111Z,1636149545.111 [BPC1] Loaded 2021-11-05T21:59:05.111Z,1636149545.111 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-11-05T21:59:05.181Z,1636149545.181 [DataOverHttps] Loaded 2021-11-05T21:59:05.182Z,1636149545.182 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-11-05T21:59:05.183Z,1636149545.183 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A794E0 2021-11-05T21:59:05.183Z,1636149545.183 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2784 2021-11-05T21:59:05.202Z,1636149545.202 [Depth_Keller] Loaded 2021-11-05T21:59:05.203Z,1636149545.203 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-11-05T21:59:05.207Z,1636149545.207 [DropWeight] Loaded 2021-11-05T21:59:05.208Z,1636149545.208 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-11-05T21:59:05.267Z,1636149545.267 [NAL9602] Loaded 2021-11-05T21:59:05.268Z,1636149545.268 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-11-05T21:59:05.297Z,1636149545.297 [Onboard] Loaded 2021-11-05T21:59:05.297Z,1636149545.297 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-11-05T21:59:05.298Z,1636149545.298 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2021-11-05T21:59:05.298Z,1636149545.298 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2785 2021-11-05T21:59:05.306Z,1636149545.306 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1 2021-11-05T21:59:05.316Z,1636149545.316 [PowerOnly] Loaded 2021-11-05T21:59:05.316Z,1636149545.316 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2021-11-05T21:59:05.328Z,1636149545.328 [Power24vConverter] Loaded 2021-11-05T21:59:05.328Z,1636149545.328 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-11-05T21:59:05.341Z,1636149545.341 [Radio_Surface] Loaded 2021-11-05T21:59:05.341Z,1636149545.341 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-11-05T21:59:05.342Z,1636149545.342 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0 2021-11-05T21:59:05.342Z,1636149545.342 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2786 2021-11-05T21:59:05.343Z,1636149545.343 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-11-05T21:59:05.344Z,1636149545.344 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-11-05T21:59:05.487Z,1636149545.487 [SBIT](DEBUG): Construct Startup Built In Test. 2021-11-05T21:59:05.496Z,1636149545.496 [SBIT] Loaded 2021-11-05T21:59:05.497Z,1636149545.497 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-11-05T21:59:05.499Z,1636149545.499 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-11-05T21:59:05.513Z,1636149545.513 [IBIT] Loaded 2021-11-05T21:59:05.513Z,1636149545.513 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-11-05T21:59:05.519Z,1636149545.519 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-11-05T21:59:05.622Z,1636149545.622 [CBIT] Loaded 2021-11-05T21:59:05.622Z,1636149545.622 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-11-05T21:59:05.622Z,1636149545.622 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-11-05T21:59:05.623Z,1636149545.623 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-11-05T21:59:05.651Z,1636149545.651 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-11-05T21:59:05.657Z,1636149545.657 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-11-05T21:59:05.660Z,1636149545.660 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-11-05T21:59:05.670Z,1636149545.670 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-11-05T21:59:05.672Z,1636149545.672 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0 2021-11-05T21:59:05.672Z,1636149545.672 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2787 2021-11-05T21:59:05.676Z,1636149545.676 [Supervisor](INFO): Main Thread ID is 830 2021-11-05T21:59:05.677Z,1636149545.677 [Supervisor](DEBUG): Running supervisor. 2021-11-05T21:59:05.677Z,1636149545.677 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2788 2021-11-05T21:59:05.678Z,1636149545.678 [CommandExec](INFO): Initializing the command executive. 2021-11-05T21:59:05.679Z,1636149545.679 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2789 2021-11-05T21:59:05.682Z,1636149545.682 [controlThread ThreadHandler](INFO): Handler Thread ID is 2790 2021-11-05T21:59:05.683Z,1636149545.683 [controlThread](DEBUG): Initializing ControlThread 2021-11-05T21:59:05.684Z,1636149545.684 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-11-05T21:59:05.685Z,1636149545.685 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-11-05T21:59:05.686Z,1636149545.686 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-11-05T21:59:05.690Z,1636149545.690 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-11-05T21:59:05.692Z,1636149545.692 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-11-05T21:59:05.692Z,1636149545.692 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-11-05T21:59:05.693Z,1636149545.693 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-11-05T21:59:05.693Z,1636149545.693 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-11-05T21:59:05.693Z,1636149545.693 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-11-05T21:59:05.694Z,1636149545.694 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-11-05T21:59:05.694Z,1636149545.694 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-11-05T21:59:05.695Z,1636149545.695 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-11-05T21:59:05.695Z,1636149545.695 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-11-05T21:59:05.700Z,1636149545.700 [SBIT](INFO): Initialize SBIT Component. 2021-11-05T21:59:05.700Z,1636149545.700 [SBIT](IMPORTANT): git: 2021-10-07-21-gf3936c28b 2021-11-05T21:59:05.700Z,1636149545.700 [SBIT](INFO): git hash: f3936c28b3ec5440459a0f9ae6e0174c5c4ab278 2021-11-05T21:59:05.701Z,1636149545.701 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-11-05T21:59:05.702Z,1636149545.702 [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 2021-11-05T21:59:05.703Z,1636149545.703 [SBIT](INFO): Beginning SBIT in 46.000000 seconds. 2021-11-05T21:59:05.704Z,1636149545.704 [IBIT](INFO): Initialize IBIT Component. 2021-11-05T21:59:05.705Z,1636149545.705 [CBIT](DEBUG): Initialize CBIT Component. 2021-11-05T21:59:05.706Z,1636149545.706 [logger ThreadHandler](INFO): Handler Thread ID is 2791 2021-11-05T21:59:05.717Z,1636149545.717 [CBIT](DEBUG): Initialized mux pins. 2021-11-05T21:59:05.718Z,1636149545.718 [CBIT](DEBUG): Initializing the watchdog timer. 2021-11-05T21:59:05.726Z,1636149545.726 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2792 2021-11-05T21:59:05.726Z,1636149545.726 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-11-05T21:59:05.732Z,1636149545.732 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2794 2021-11-05T21:59:05.734Z,1636149545.734 [WetLabsBB2FL](INFO): Powering up 2021-11-05T21:59:05.735Z,1636149545.735 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2796 2021-11-05T21:59:05.741Z,1636149545.741 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2021-11-05T21:59:05.742Z,1636149545.742 [CBIT](DEBUG): Initializing heartbeat. 2021-11-05T21:59:05.743Z,1636149545.743 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2797 2021-11-05T21:59:05.744Z,1636149545.744 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-11-05T21:59:05.754Z,1636149545.754 [Onboard ThreadHandler](INFO): Handler Thread ID is 2798 2021-11-05T21:59:05.771Z,1636149545.771 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2799 2021-11-05T21:59:05.794Z,1636149545.794 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2800 2021-11-05T21:59:05.797Z,1636149545.797 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-11-05T21:59:05.797Z,1636149545.797 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-11-05T21:59:05.798Z,1636149545.798 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-11-05T21:59:05.798Z,1636149545.798 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-11-05T21:59:05.798Z,1636149545.798 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-11-05T21:59:05.798Z,1636149545.798 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-11-05T21:59:05.798Z,1636149545.798 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-11-05T21:59:05.798Z,1636149545.798 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-11-05T21:59:05.799Z,1636149545.799 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-11-05T21:59:05.799Z,1636149545.799 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-11-05T21:59:05.799Z,1636149545.799 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-11-05T21:59:05.799Z,1636149545.799 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-11-05T21:59:05.799Z,1636149545.799 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-11-05T21:59:05.800Z,1636149545.800 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-11-05T21:59:05.800Z,1636149545.800 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-11-05T21:59:05.800Z,1636149545.800 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-11-05T21:59:05.813Z,1636149545.813 [CBIT](DEBUG): Deactivating GF circuits. 2021-11-05T21:59:05.814Z,1636149545.814 [CBIT](DEBUG): Deactivating emergency mode. 2021-11-05T21:59:05.849Z,1636149545.849 [CBIT](DEBUG): Backplane powered. 2021-11-05T21:59:05.850Z,1636149545.850 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-11-05T21:59:05.864Z,1636149545.864 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-11-05T21:59:05.896Z,1636149545.896 [MissionManager](DEBUG): 2021-11-05T21:59:05.897Z,1636149545.897 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-11-05T21:59:05.990Z,1636149545.990 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-11-05T21:59:05.991Z,1636149545.991 [Default:A.Wait](DEBUG): Construct Wait. 2021-11-05T21:59:06.002Z,1636149546.002 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-11-05T21:59:06.021Z,1636149546.021 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-11-05T21:59:06.024Z,1636149546.024 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-11-05T21:59:06.046Z,1636149546.046 [Default:E.Execute](DEBUG): Construct Execute. 2021-11-05T21:59:06.058Z,1636149546.058 [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 2021-11-05T21:59:06.078Z,1636149546.078 [controlThread](DEBUG): Component order: CycleStarter,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2021-11-05T21:59:06.110Z,1636149546.110 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-11-05T21:59:06.176Z,1636149546.176 [Power24vConverter](INFO): Powering up. 2021-11-05T21:59:06.177Z,1636149546.177 [Radio_Surface](INFO): Powering up 2021-11-05T21:59:06.207Z,1636149546.207 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-11-05T21:59:06.214Z,1636149546.214 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-11-05T21:59:06.215Z,1636149546.215 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-11-05T21:59:06.230Z,1636149546.230 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-11-05T21:59:06.231Z,1636149546.231 [MassServo](DEBUG): Initializing EZServoServo. 2021-11-05T21:59:06.238Z,1636149546.238 [MassServo](DEBUG): Initializing MassServo. 2021-11-05T21:59:06.239Z,1636149546.239 [RudderServo](DEBUG): Initializing EZServoServo. 2021-11-05T21:59:06.254Z,1636149546.254 [RudderServo](DEBUG): Initializing RudderServo. 2021-11-05T21:59:06.255Z,1636149546.255 [ThrusterHE](DEBUG): Initializing EZServoServo. 2021-11-05T21:59:06.262Z,1636149546.262 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2021-11-05T21:59:06.263Z,1636149546.263 [CommandExec](FAULT): Scheduling is paused 2021-11-05T21:59:06.264Z,1636149546.264 [CBIT](INFO): Critical error at 20211105T215905 2021-11-05T21:59:06.264Z,1636149546.264 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-11-05T21:59:08.346Z,1636149548.346 [WetLabsBB2FL](INFO): Powering down 2021-11-05T21:59:12.759Z,1636149552.759 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-11-05T21:59:35.588Z,1636149575.588 [NAL9602](INFO): Powering up NAL9602 2021-11-05T21:59:46.495Z,1636149586.495 [NAL9602](INFO): NAL9602 initialized 2021-11-05T21:59:52.182Z,1636149592.182 [SBIT](IMPORTANT): Beginning Startup BIT 2021-11-05T21:59:52.190Z,1636149592.190 [CBIT](IMPORTANT): Beginning ground fault scan 2021-11-05T22:00:03.131Z,1636149603.131 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.849999 CHAN A1 (24V): 0.802751 CHAN A2 (12V): 0.878907 CHAN A3 (5V): 0.385784 CHAN B0 (3.3V): 0.251537 CHAN B1 (3.15aV): 0.307585 CHAN B2 (3.15bV): 0.306796 CHAN B3 (GND): 0.004146 OPEN: 0.024872 Full Scale: +/- 1 mA 2021-11-05T22:00:34.171Z,1636149634.171 [NAL9602](INFO): SBD MO Status=2, MOMSN=3826, MT Status=2, MTMSN=0 2021-11-05T22:00:34.172Z,1636149634.172 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-05T22:00:46.374Z,1636149646.374 [SBIT](IMPORTANT): SBIT PASSED 2021-11-05T22:00:46.374Z,1636149646.374 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2021-11-05T22:00:46.375Z,1636149646.375 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2021-11-05T22:00:46.375Z,1636149646.375 [SBIT](IMPORTANT): CBIT.gf24Offset=149 microampere; 2021-11-05T22:00:46.375Z,1636149646.375 [SBIT](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool; 2021-11-05T22:00:46.375Z,1636149646.375 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=5 ampere_hour; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11 volt; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): NAL9602.fastGPSFix=1 bool; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): PAR_Licor.adcCal=8.76539e-06 microampere_per_count; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): PAR_Licor.darkCount=4 count; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): PAR_Licor.multiplier=269.34 micromole_per_second_per_square_meter_per_microampere; 2021-11-05T22:00:46.376Z,1636149646.376 [SBIT](IMPORTANT): PAR_Licor.parCal=1.221 none; 2021-11-05T22:00:46.377Z,1636149646.377 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=360.448324 cubic_centimeter; 2021-11-05T22:00:46.377Z,1636149646.377 [SBIT](IMPORTANT): VerticalControl.massDefault=6.548438 millimeter; 2021-11-05T22:00:46.377Z,1636149646.377 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-27 millimeter; 2021-11-05T22:00:46.377Z,1636149646.377 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=37 millimeter; 2021-11-05T22:00:46.857Z,1636149646.857 [MissionManager](IMPORTANT): Started mission Startup 2021-11-05T22:00:46.874Z,1636149646.874 [Startup] Running Loop=1 2021-11-05T22:00:46.874Z,1636149646.874 [Startup](DEBUG): Aggregate::initialize Startup 2021-11-05T22:00:46.874Z,1636149646.874 [Startup:A.GoToSurface] Running Loop=1 2021-11-05T22:00:46.874Z,1636149646.874 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-05T22:00:46.875Z,1636149646.875 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-11-05T22:00:46.876Z,1636149646.876 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-11-05T22:00:46.876Z,1636149646.876 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-11-05T22:00:46.876Z,1636149646.876 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-05T22:00:46.877Z,1636149646.877 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-05T22:00:46.878Z,1636149646.878 [Startup:StartupSatComms] Running Loop=1 2021-11-05T22:00:46.878Z,1636149646.878 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-11-05T22:00:46.879Z,1636149646.879 [Startup:StartupSatComms:A] Running Loop=1 2021-11-05T22:00:47.163Z,1636149647.163 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-11-05T22:01:10.129Z,1636149670.129 [NAL9602](INFO): SBD MO Status=2, MOMSN=3826, MT Status=2, MTMSN=0 2021-11-05T22:01:10.129Z,1636149670.129 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-05T22:01:46.898Z,1636149706.898 [Startup:StartupSatComms:A](INFO): Timed out from 2021-11-05T22:00:46.9Z 2021-11-05T22:01:46.898Z,1636149706.898 [Startup:StartupSatComms:A] Stopped 2021-11-05T22:01:46.898Z,1636149706.898 [Startup:StartupSatComms:B] Running Loop=1 2021-11-05T22:01:47.306Z,1636149707.306 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-11-05T22:01:48.913Z,1636149708.913 [NAL9602](INFO): SBD MO Status=2, MOMSN=3826, MT Status=2, MTMSN=0 2021-11-05T22:01:48.913Z,1636149708.913 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-05T22:02:05.892Z,1636149725.892 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-11-05T22:02:05.892Z,1636149725.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-11-05T22:02:05.894Z,1636149725.894 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2021-11-05T22:02:05.894Z,1636149725.894 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-11-05T22:02:05.906Z,1636149725.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-11-05T22:02:05.907Z,1636149725.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-11-05T22:02:06.321Z,1636149726.321 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-11-05T22:02:06.321Z,1636149726.321 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-11-05T22:02:06.322Z,1636149726.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-11-05T22:02:06.322Z,1636149726.322 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2021-11-05T22:02:06.693Z,1636149726.693 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-11-05T22:02:28.840Z,1636149748.840 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004414 2021-11-05T22:02:47.174Z,1636149767.174 [Startup:StartupSatComms:B](INFO): Timed out from 2021-11-05T22:01:46.9Z 2021-11-05T22:02:47.175Z,1636149767.175 [Startup:StartupSatComms:B] Stopped 2021-11-05T22:02:47.175Z,1636149767.175 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-11-05T22:02:47.175Z,1636149767.175 [Startup:StartupSatComms] Stopped 2021-11-05T22:02:47.175Z,1636149767.175 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-11-05T22:02:47.176Z,1636149767.176 [Startup](INFO): Completed Startup 2021-11-05T22:02:47.176Z,1636149767.176 [MissionManager](INFO): Startup is completed. 2021-11-05T22:02:47.176Z,1636149767.176 [MissionManager](INFO): Uninitializing Mission Startup 2021-11-05T22:02:47.176Z,1636149767.176 [Startup] Stopped 2021-11-05T22:02:47.176Z,1636149767.176 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-11-05T22:02:47.176Z,1636149767.176 [Startup:A.GoToSurface] Stopped 2021-11-05T22:02:47.176Z,1636149767.176 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-11-05T22:02:47.730Z,1636149767.730 [MissionManager](IMPORTANT): Started mission Default 2021-11-05T22:02:47.730Z,1636149767.730 [Default] Running Loop=1 2021-11-05T22:02:47.730Z,1636149767.730 [Default](DEBUG): Aggregate::initialize Default 2021-11-05T22:02:47.730Z,1636149767.730 [Default:B.GoToSurface] Running Loop=1 2021-11-05T22:02:47.730Z,1636149767.730 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-05T22:02:47.732Z,1636149767.732 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-11-05T22:02:47.732Z,1636149767.732 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-11-05T22:02:47.734Z,1636149767.734 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-11-05T22:02:47.736Z,1636149767.736 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-05T22:02:47.736Z,1636149767.736 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-05T22:02:47.736Z,1636149767.736 [Default:A.Wait] Running Loop=1 2021-11-05T22:02:47.778Z,1636149767.778 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-11-05T22:02:54.772Z,1636149774.772 [NAL9602](INFO): SBD MO Status=2, MOMSN=3826, MT Status=2, MTMSN=0 2021-11-05T22:02:54.772Z,1636149774.772 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-05T22:03:00.831Z,1636149780.831 [Default:A.Wait](INFO): Done Waiting. 2021-11-05T22:03:00.831Z,1636149780.831 [Default:A.Wait] Stopped 2021-11-05T22:03:00.831Z,1636149780.831 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-11-05T22:03:01.356Z,1636149781.356 [Default:CheckIn] Running Loop=1 2021-11-05T22:03:01.356Z,1636149781.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-11-05T22:03:01.356Z,1636149781.356 [Default:CheckIn:Read_GPS] Running Loop=1 2021-11-05T22:03:01.698Z,1636149781.698 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-11-05T22:03:30.045Z,1636149810.045 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20211105T204905/Courier0022.lzma 2021-11-05T22:03:31.047Z,1636149811.047 [DataOverHttps](INFO): Moved sent file to Logs/20211105T204905/Courier0022.lzma.bak 2021-11-05T22:03:31.047Z,1636149811.047 [DataOverHttps](INFO): SBD MOMSN=16212810 2021-11-05T22:04:50.341Z,1636149890.341 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-11-05T22:05:06.927Z,1636149906.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-11-05T22:05:06.927Z,1636149906.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-11-05T22:05:06.929Z,1636149906.929 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2021-11-05T22:05:06.929Z,1636149906.929 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-11-05T22:05:06.975Z,1636149906.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-11-05T22:05:06.975Z,1636149906.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2021-11-05T22:05:07.365Z,1636149907.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-11-05T22:05:07.365Z,1636149907.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-11-05T22:05:07.370Z,1636149907.370 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2021-11-05T22:05:07.370Z,1636149907.370 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2021-11-05T22:05:07.728Z,1636149907.728 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2021-11-05T22:07:27.223Z,1636150047.223 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2021-11-05T22:07:27.450Z,1636150047.450 [CBIT](CRITICAL): Run-time exception #7 2021-11-05T22:07:27.450Z,1636150047.450 [CBIT] Software Fault, FailCount= 1 2021-11-05T22:07:27.450Z,1636150047.450 [CBIT](ERROR): Software Fault 2021-11-05T22:07:27.452Z,1636150047.452 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-05T22:07:27.453Z,1636150047.453 [SyncHandler](INFO): Protected caller Thread ID is 2981 2021-11-05T22:07:28.054Z,1636150048.054 [ElevatorServo](ERROR): getPosition uart error serial timeout 2021-11-05T22:07:28.054Z,1636150048.054 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2021-11-05T22:07:28.054Z,1636150048.054 [ElevatorServo] Communications Fault, FailCount= 1 2021-11-05T22:07:28.054Z,1636150048.054 [ElevatorServo](ERROR): Communications Fault 2021-11-05T22:07:28.362Z,1636150048.362 [MassServo](FAULT): Mass Shifter uart error: serial timeout 2021-11-05T22:07:28.362Z,1636150048.362 [MassServo] Communications Fault, FailCount= 1 2021-11-05T22:07:28.362Z,1636150048.362 [MassServo](ERROR): Communications Fault 2021-11-05T22:07:28.363Z,1636150048.363 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-11-05T22:07:28.364Z,1636150048.364 [CBIT](DEBUG): Powering off loads. 2021-11-05T22:07:28.377Z,1636150048.377 [CBIT](DEBUG): Disabling WDT. 2021-11-05T22:07:28.394Z,1636150048.394 [CBIT](DEBUG): Opening all GF detection circuits. 2021-11-05T22:07:28.572Z,1636150048.572 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-11-05T22:07:28.572Z,1636150048.572 [ElevatorServo](INFO): Powering down 2021-11-05T22:07:28.586Z,1636150048.586 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-11-05T22:07:28.586Z,1636150048.586 [MassServo](INFO): Powering down 2021-11-05T22:07:28.953Z,1636150048.953 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-11-05T22:07:29.292Z,1636150049.292 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-11-05T22:07:29.293Z,1636150049.293 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-11-05T22:07:37.301Z,1636150057.301 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-11-05T22:07:37.301Z,1636150057.301 [AHRS_M2] Data Fault, FailCount= 1 2021-11-05T22:07:37.302Z,1636150057.302 [AHRS_M2](ERROR): Data Fault 2021-11-05T22:07:37.708Z,1636150057.708 [AHRS_M2](INFO): Powering down 2021-11-05T22:07:58.409Z,1636150078.409 [NAL9602](ERROR): getFix uart error: serial timeout 2021-11-05T22:07:58.509Z,1636150078.509 [Depth_Keller](ERROR): Pressure reading out of range: 1628.471313 decibar 2021-11-05T22:08:01.722Z,1636150081.722 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-11-05T22:08:01.738Z,1636150081.738 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-05T22:03:01.4Z 2021-11-05T22:08:01.738Z,1636150081.738 [Default:CheckIn:Read_GPS] Stopped 2021-11-05T22:08:01.738Z,1636150081.738 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-11-05T22:08:02.291Z,1636150082.291 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-11-05T22:08:06.369Z,1636150086.369 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=62.929436 2021-11-05T22:08:07.991Z,1636150087.991 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-11-05T22:08:07.991Z,1636150087.991 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-11-05T22:08:07.992Z,1636150087.992 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2021-11-05T22:08:07.992Z,1636150087.992 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2021-11-05T22:08:28.583Z,1636150108.583 [NAL9602](ERROR): parseGSV uart error: serial timeout 2021-11-05T22:08:28.995Z,1636150108.995 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2021-11-05T22:08:29.509Z,1636150109.509 [NAL9602](ERROR): received: 2021-11-05T22:08:29.608Z,1636150109.608 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-11-05T22:08:29.614Z,1636150109.614 [Depth_Keller](ERROR): Pressure reading out of range: 815.405518 decibar 2021-11-05T22:08:40.851Z,1636150120.851 [BPC1](ERROR): BPC1B buffer size (AFA bytes) exceeded the max device response limit. 2021-11-05T22:08:40.851Z,1636150120.851 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-11-05T22:09:00.758Z,1636150140.758 [NAL9602](ERR