2020-09-23T19:56:33.371Z,1600890993.371 [Supervisor](DEBUG): Initializing supervisor. 2020-09-23T19:56:33.377Z,1600890993.377 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-09-23T19:56:33.377Z,1600890993.377 [SyncHandler](INFO): Protected caller Thread ID is 1536 2020-09-23T19:56:33.378Z,1600890993.378 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-09-23T19:56:33.379Z,1600890993.379 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-09-23T19:56:33.379Z,1600890993.379 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1537 2020-09-23T19:56:33.383Z,1600890993.383 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-09-23T19:56:33.400Z,1600890993.400 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-09-23T19:56:33.401Z,1600890993.401 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-09-23T19:56:33.401Z,1600890993.401 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1538 2020-09-23T19:56:33.403Z,1600890993.403 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-09-23T19:56:33.404Z,1600890993.404 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-09-23T19:56:33.405Z,1600890993.405 [logger ThreadHandler](INFO): Protected caller Thread ID is 1539 2020-09-23T19:56:33.408Z,1600890993.408 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-09-23T19:56:33.408Z,1600890993.408 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-09-23T19:56:33.410Z,1600890993.410 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-09-23T19:56:33.507Z,1600890993.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-09-23T19:56:33.508Z,1600890993.508 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-09-23T19:56:34.119Z,1600890994.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-09-23T19:56:34.119Z,1600890994.119 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-09-23T19:56:34.323Z,1600890994.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-09-23T19:56:34.324Z,1600890994.324 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-09-23T19:56:34.405Z,1600890994.405 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-09-23T19:56:34.511Z,1600890994.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-09-23T19:56:34.511Z,1600890994.511 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-09-23T19:56:34.594Z,1600890994.594 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-09-23T19:56:34.697Z,1600890994.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-09-23T19:56:34.697Z,1600890994.697 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-09-23T19:56:35.004Z,1600890995.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-09-23T19:56:35.004Z,1600890995.004 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-09-23T19:56:35.506Z,1600890995.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-09-23T19:56:35.506Z,1600890995.506 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-09-23T19:56:35.755Z,1600890995.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-09-23T19:56:35.756Z,1600890995.756 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-09-23T19:56:35.955Z,1600890995.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-09-23T19:56:35.955Z,1600890995.955 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-09-23T19:56:36.377Z,1600890996.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-09-23T19:56:36.378Z,1600890996.378 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-09-23T19:56:36.608Z,1600890996.608 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-09-23T19:56:36.609Z,1600890996.609 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-09-23T19:56:36.818Z,1600890996.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-09-23T19:56:36.818Z,1600890996.818 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-09-23T19:56:37.186Z,1600890997.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-09-23T19:56:37.186Z,1600890997.186 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-09-23T19:56:37.532Z,1600890997.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-09-23T19:56:37.534Z,1600890997.534 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-09-23T19:56:37.535Z,1600890997.535 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-09-23T19:56:37.636Z,1600890997.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-09-23T19:56:37.797Z,1600890997.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-09-23T19:56:37.909Z,1600890997.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-09-23T19:56:37.995Z,1600890997.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-09-23T19:56:38.092Z,1600890998.092 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-09-23T19:56:38.290Z,1600890998.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-09-23T19:56:38.524Z,1600890998.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-09-23T19:56:38.524Z,1600890998.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-09-23T19:56:38.620Z,1600890998.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-09-23T19:56:38.720Z,1600890998.720 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-09-23T19:56:38.849Z,1600890998.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-09-23T19:56:38.951Z,1600890998.951 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2020-09-23T19:56:38.952Z,1600890998.952 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-09-23T19:56:38.965Z,1600890998.965 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-09-23T19:56:39.045Z,1600890999.045 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-09-23T19:56:39.046Z,1600890999.046 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-09-23T19:56:39.101Z,1600890999.101 [VerticalControl](DEBUG): Construct VerticalControl. 2020-09-23T19:56:39.215Z,1600890999.215 [VerticalControl] Loaded 2020-09-23T19:56:39.215Z,1600890999.215 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-09-23T19:56:39.220Z,1600890999.220 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-09-23T19:56:39.302Z,1600890999.302 [HorizontalControl] Loaded 2020-09-23T19:56:39.303Z,1600890999.303 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-09-23T19:56:39.307Z,1600890999.307 [SpeedControl](DEBUG): Construct SpeedControl. 2020-09-23T19:56:39.313Z,1600890999.313 [SpeedControl] Loaded 2020-09-23T19:56:39.313Z,1600890999.313 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-09-23T19:56:39.318Z,1600890999.318 [LoopControl](DEBUG): Construct LoopControl. 2020-09-23T19:56:39.319Z,1600890999.319 [LoopControl] Loaded 2020-09-23T19:56:39.319Z,1600890999.319 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-09-23T19:56:39.319Z,1600890999.319 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-09-23T19:56:39.320Z,1600890999.320 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-09-23T19:56:39.366Z,1600890999.366 [DepthRateCalculator] Loaded 2020-09-23T19:56:39.367Z,1600890999.367 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-09-23T19:56:39.376Z,1600890999.376 [PitchRateCalculator] Loaded 2020-09-23T19:56:39.377Z,1600890999.377 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-09-23T19:56:39.396Z,1600890999.396 [SpeedCalculator] Loaded 2020-09-23T19:56:39.397Z,1600890999.397 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-09-23T19:56:39.422Z,1600890999.422 [TempGradientCalculator] Loaded 2020-09-23T19:56:39.422Z,1600890999.422 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-09-23T19:56:39.431Z,1600890999.431 [YawRateCalculator] Loaded 2020-09-23T19:56:39.431Z,1600890999.431 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-09-23T19:56:39.465Z,1600890999.465 [ElevatorOffsetCalculator] Loaded 2020-09-23T19:56:39.465Z,1600890999.465 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-09-23T19:56:39.466Z,1600890999.466 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-09-23T19:56:39.466Z,1600890999.466 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-09-23T19:56:39.495Z,1600890999.495 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-09-23T19:56:39.495Z,1600890999.495 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-09-23T19:56:39.552Z,1600890999.552 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-09-23T19:56:39.553Z,1600890999.553 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-09-23T19:56:39.866Z,1600890999.866 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-09-23T19:56:39.866Z,1600890999.866 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-09-23T19:56:39.968Z,1600890999.968 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-09-23T19:56:39.968Z,1600890999.968 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-09-23T19:56:40.375Z,1600891000.375 [AHRS_M2] Loaded 2020-09-23T19:56:40.375Z,1600891000.375 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-09-23T19:56:40.421Z,1600891000.421 [BackseatComponent] Loaded 2020-09-23T19:56:40.421Z,1600891000.421 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2020-09-23T19:56:40.422Z,1600891000.422 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408F84E0 2020-09-23T19:56:40.423Z,1600891000.423 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1621 2020-09-23T19:56:42.379Z,1600891002.379 [BPC1] Loaded 2020-09-23T19:56:42.379Z,1600891002.379 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-09-23T19:56:42.459Z,1600891002.459 [DataOverHttps] Loaded 2020-09-23T19:56:42.460Z,1600891002.460 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-09-23T19:56:42.461Z,1600891002.461 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409284E0 2020-09-23T19:56:42.461Z,1600891002.461 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1622 2020-09-23T19:56:42.497Z,1600891002.497 [Depth_Keller] Loaded 2020-09-23T19:56:42.497Z,1600891002.497 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-09-23T19:56:42.506Z,1600891002.506 [DropWeight] Loaded 2020-09-23T19:56:42.507Z,1600891002.507 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-09-23T19:56:42.625Z,1600891002.625 [NAL9602] Loaded 2020-09-23T19:56:42.626Z,1600891002.626 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-09-23T19:56:42.679Z,1600891002.679 [Onboard] Loaded 2020-09-23T19:56:42.680Z,1600891002.680 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-09-23T19:56:42.681Z,1600891002.681 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409584E0 2020-09-23T19:56:42.681Z,1600891002.681 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1623 2020-09-23T19:56:42.705Z,1600891002.705 [Radio_Surface] Loaded 2020-09-23T19:56:42.705Z,1600891002.705 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-09-23T19:56:42.706Z,1600891002.706 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409884E0 2020-09-23T19:56:42.707Z,1600891002.707 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1624 2020-09-23T19:56:42.865Z,1600891002.865 [DAT] Loaded 2020-09-23T19:56:42.865Z,1600891002.865 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-09-23T19:56:42.865Z,1600891002.865 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-09-23T19:56:42.866Z,1600891002.866 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-09-23T19:56:42.987Z,1600891002.987 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-09-23T19:56:42.987Z,1600891002.987 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-09-23T19:56:43.013Z,1600891003.013 [NavChart] Loaded 2020-09-23T19:56:43.013Z,1600891003.013 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-09-23T19:56:43.021Z,1600891003.021 [UniversalFixResidualReporter] Loaded 2020-09-23T19:56:43.021Z,1600891003.021 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-09-23T19:56:43.022Z,1600891003.022 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-09-23T19:56:43.022Z,1600891003.022 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-09-23T19:56:43.167Z,1600891003.167 [SBIT](DEBUG): Construct Startup Built In Test. 2020-09-23T19:56:43.178Z,1600891003.178 [SBIT] Loaded 2020-09-23T19:56:43.179Z,1600891003.179 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-09-23T19:56:43.184Z,1600891003.184 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-09-23T19:56:43.198Z,1600891003.198 [IBIT] Loaded 2020-09-23T19:56:43.198Z,1600891003.198 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-09-23T19:56:43.206Z,1600891003.206 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-09-23T19:56:43.349Z,1600891003.349 [CBIT] Loaded 2020-09-23T19:56:43.349Z,1600891003.349 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-09-23T19:56:43.350Z,1600891003.350 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-09-23T19:56:43.350Z,1600891003.350 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-09-23T19:56:43.481Z,1600891003.481 [BuoyancyServo] Loaded 2020-09-23T19:56:43.482Z,1600891003.482 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-09-23T19:56:43.516Z,1600891003.516 [ElevatorServo] Loaded 2020-09-23T19:56:43.516Z,1600891003.516 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-09-23T19:56:43.549Z,1600891003.549 [MassServo] Loaded 2020-09-23T19:56:43.549Z,1600891003.549 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-09-23T19:56:43.581Z,1600891003.581 [RudderServo] Loaded 2020-09-23T19:56:43.581Z,1600891003.581 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-09-23T19:56:43.613Z,1600891003.613 [ThrusterServo] Loaded 2020-09-23T19:56:43.613Z,1600891003.613 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-09-23T19:56:43.614Z,1600891003.614 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-09-23T19:56:43.614Z,1600891003.614 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-09-23T19:56:43.625Z,1600891003.625 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-09-23T19:56:43.626Z,1600891003.626 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-09-23T19:56:43.847Z,1600891003.847 [CTD_Seabird] Loaded 2020-09-23T19:56:43.847Z,1600891003.847 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-09-23T19:56:43.848Z,1600891003.848 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AB74E0 2020-09-23T19:56:43.849Z,1600891003.849 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1625 2020-09-23T19:56:43.918Z,1600891003.918 [WetLabsBB2FL] Loaded 2020-09-23T19:56:43.918Z,1600891003.918 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-09-23T19:56:43.919Z,1600891003.919 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AE74E0 2020-09-23T19:56:43.919Z,1600891003.919 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1626 2020-09-23T19:56:43.920Z,1600891003.920 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-09-23T19:56:43.926Z,1600891003.926 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-09-23T19:56:43.928Z,1600891003.928 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-09-23T19:56:43.937Z,1600891003.937 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-09-23T19:56:43.938Z,1600891003.938 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B174E0 2020-09-23T19:56:43.938Z,1600891003.938 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1627 2020-09-23T19:56:43.943Z,1600891003.943 [Supervisor](INFO): Main Thread ID is 828 2020-09-23T19:56:43.943Z,1600891003.943 [Supervisor](DEBUG): Running supervisor. 2020-09-23T19:56:43.944Z,1600891003.944 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1628 2020-09-23T19:56:43.946Z,1600891003.946 [controlThread ThreadHandler](INFO): Handler Thread ID is 1629 2020-09-23T19:56:43.947Z,1600891003.947 [controlThread](DEBUG): Initializing ControlThread 2020-09-23T19:56:43.948Z,1600891003.948 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-09-23T19:56:43.950Z,1600891003.950 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-09-23T19:56:43.950Z,1600891003.950 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-09-23T19:56:43.951Z,1600891003.951 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-09-23T19:56:43.951Z,1600891003.951 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-09-23T19:56:43.951Z,1600891003.951 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-09-23T19:56:43.952Z,1600891003.952 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-09-23T19:56:43.952Z,1600891003.952 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-09-23T19:56:43.953Z,1600891003.953 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-09-23T19:56:43.953Z,1600891003.953 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-09-23T19:56:43.958Z,1600891003.958 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-09-23T19:56:43.959Z,1600891003.959 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-09-23T19:56:43.959Z,1600891003.959 [SBIT](INFO): Initialize SBIT Component. 2020-09-23T19:56:43.960Z,1600891003.960 [SBIT](IMPORTANT): git: 2020-09-22a-68-g5988b4d 2020-09-23T19:56:43.960Z,1600891003.960 [SBIT](INFO): git hash: 5988b4dc1e28244940a3f4a6d5599f20c37273c2 2020-09-23T19:56:43.960Z,1600891003.960 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-09-23T19:56:43.961Z,1600891003.961 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020 2020-09-23T19:56:43.963Z,1600891003.963 [SBIT](INFO): Beginning SBIT in 75.000000 seconds. 2020-09-23T19:56:43.963Z,1600891003.963 [IBIT](INFO): Initialize IBIT Component. 2020-09-23T19:56:43.964Z,1600891003.964 [CBIT](DEBUG): Initialize CBIT Component. 2020-09-23T19:56:43.965Z,1600891003.965 [logger ThreadHandler](INFO): Handler Thread ID is 1630 2020-09-23T19:56:43.976Z,1600891003.976 [CBIT](DEBUG): Initialized mux pins. 2020-09-23T19:56:43.976Z,1600891003.976 [CBIT](DEBUG): Initializing the watchdog timer. 2020-09-23T19:56:43.985Z,1600891003.985 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1631 2020-09-23T19:56:43.996Z,1600891003.996 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1632 2020-09-23T19:56:43.997Z,1600891003.997 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-09-23T19:56:43.000Z,1600891004.000 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2020-09-23T19:56:43.000Z,1600891004.000 [CBIT](DEBUG): Initializing heartbeat. 2020-09-23T19:56:44.008Z,1600891004.008 [Onboard ThreadHandler](INFO): Handler Thread ID is 1633 2020-09-23T19:56:44.026Z,1600891004.026 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1634 2020-09-23T19:56:44.044Z,1600891004.044 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1635 2020-09-23T19:56:44.045Z,1600891004.045 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-09-23T19:56:44.049Z,1600891004.049 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1637 2020-09-23T19:56:44.052Z,1600891004.052 [WetLabsBB2FL](INFO): Powering up 2020-09-23T19:56:44.053Z,1600891004.053 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1639 2020-09-23T19:56:44.057Z,1600891004.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-09-23T19:56:44.057Z,1600891004.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-09-23T19:56:44.057Z,1600891004.057 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-09-23T19:56:44.057Z,1600891004.057 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-09-23T19:56:44.058Z,1600891004.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-09-23T19:56:44.058Z,1600891004.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-09-23T19:56:44.058Z,1600891004.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-09-23T19:56:44.058Z,1600891004.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-09-23T19:56:44.058Z,1600891004.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-09-23T19:56:44.058Z,1600891004.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-09-23T19:56:44.059Z,1600891004.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-09-23T19:56:44.059Z,1600891004.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-09-23T19:56:44.059Z,1600891004.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-09-23T19:56:44.059Z,1600891004.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-09-23T19:56:44.059Z,1600891004.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-09-23T19:56:44.059Z,1600891004.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-09-23T19:56:44.072Z,1600891004.072 [CBIT](DEBUG): Deactivating GF circuits. 2020-09-23T19:56:44.072Z,1600891004.072 [CBIT](DEBUG): Deactivating emergency mode. 2020-09-23T19:56:44.108Z,1600891004.108 [CBIT](DEBUG): Backplane powered. 2020-09-23T19:56:44.110Z,1600891004.110 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-09-23T19:56:44.125Z,1600891004.125 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-09-23T19:56:44.149Z,1600891004.149 [MissionManager](DEBUG): 2020-09-23T19:56:44.149Z,1600891004.149 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-09-23T19:56:44.244Z,1600891004.244 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-09-23T19:56:44.245Z,1600891004.245 [Default:A.Wait](DEBUG): Construct Wait. 2020-09-23T19:56:44.247Z,1600891004.247 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-09-23T19:56:44.286Z,1600891004.286 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-09-23T19:56:44.288Z,1600891004.288 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-09-23T19:56:44.319Z,1600891004.319 [Default:E.Execute](DEBUG): Construct Execute. 2020-09-23T19:56:44.326Z,1600891004.326 [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 2020-09-23T19:56:44.330Z,1600891004.330 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,DAT,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-09-23T19:56:44.344Z,1600891004.344 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-09-23T19:56:44.417Z,1600891004.417 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-09-23T19:56:44.420Z,1600891004.420 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-09-23T19:56:44.420Z,1600891004.420 [DAT](INFO): Powering up 2020-09-23T19:56:44.420Z,1600891004.420 [DAT](DEBUG): Initializing DAT. 2020-09-23T19:56:44.432Z,1600891004.432 [Radio_Surface](INFO): Powering up 2020-09-23T19:56:44.444Z,1600891004.444 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-09-23T19:56:44.469Z,1600891004.469 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-09-23T19:56:44.503Z,1600891004.503 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-09-23T19:56:44.532Z,1600891004.532 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-09-23T19:56:44.533Z,1600891004.533 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-09-23T19:56:44.553Z,1600891004.553 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-09-23T19:56:44.554Z,1600891004.554 [MassServo](DEBUG): Initializing EZServoServo. 2020-09-23T19:56:44.564Z,1600891004.564 [MassServo](DEBUG): Initializing MassServo. 2020-09-23T19:56:44.565Z,1600891004.565 [RudderServo](DEBUG): Initializing EZServoServo. 2020-09-23T19:56:44.580Z,1600891004.580 [RudderServo](DEBUG): Initializing RudderServo. 2020-09-23T19:56:44.581Z,1600891004.581 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-09-23T19:56:44.592Z,1600891004.592 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-09-23T19:56:44.595Z,1600891004.595 [CommandLine](FAULT): Scheduling is paused 2020-09-23T19:56:44.595Z,1600891004.595 [CBIT](INFO): Critical error at 20200923T195643 2020-09-23T19:56:44.595Z,1600891004.595 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-09-23T19:56:45.468Z,1600891005.468 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-09-23T19:56:45.468Z,1600891005.468 [RudderServo](FAULT): Rudder failed to initialize 2020-09-23T19:56:45.468Z,1600891005.468 [RudderServo] Communications Fault, FailCount= 1 2020-09-23T19:56:45.468Z,1600891005.468 [RudderServo](ERROR): Communications Fault 2020-09-23T19:56:45.579Z,1600891005.579 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-09-23T19:56:45.717Z,1600891005.717 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-09-23T19:56:45.717Z,1600891005.717 [RudderServo](INFO): Powering down 2020-09-23T19:56:46.408Z,1600891006.408 [RudderServo](DEBUG): Initializing EZServoServo. 2020-09-23T19:56:46.529Z,1600891006.529 [RudderServo](DEBUG): Initializing RudderServo. 2020-09-23T19:56:46.533Z,1600891006.533 [CBIT](INFO): Clearing failed state for component RudderServo 2020-09-23T19:56:46.533Z,1600891006.533 [RudderServo] No Fault, FailCount= 1 2020-09-23T19:56:46.664Z,1600891006.664 [WetLabsBB2FL](INFO): Powering down 2020-09-23T19:56:58.519Z,1600891018.519 [DAT](INFO): commRate: 800 2020-09-23T19:57:00.538Z,1600891020.538 [DAT](INFO): entering command mode 2020-09-23T19:57:00.942Z,1600891020.942 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:01.346Z,1600891021.346 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:01.750Z,1600891021.750 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:02.154Z,1600891022.154 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:02.561Z,1600891022.561 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:02.966Z,1600891022.966 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:03.366Z,1600891023.366 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:03.770Z,1600891023.770 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:04.174Z,1600891024.174 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:04.577Z,1600891024.577 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:04.982Z,1600891024.982 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:05.386Z,1600891025.386 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:05.790Z,1600891025.790 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:06.194Z,1600891026.194 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:06.597Z,1600891026.597 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:07.002Z,1600891027.002 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:07.406Z,1600891027.406 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:07.809Z,1600891027.809 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:08.214Z,1600891028.214 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:08.617Z,1600891028.617 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:09.027Z,1600891029.027 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:09.426Z,1600891029.426 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:09.830Z,1600891029.830 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:10.238Z,1600891030.238 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:10.650Z,1600891030.650 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:11.042Z,1600891031.042 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:11.446Z,1600891031.446 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:11.849Z,1600891031.849 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:12.259Z,1600891032.259 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:12.657Z,1600891032.657 [NAL9602](INFO): Powering up NAL9602 2020-09-23T19:57:12.659Z,1600891032.659 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:13.062Z,1600891033.062 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:13.466Z,1600891033.466 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:13.874Z,1600891033.874 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:14.274Z,1600891034.274 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:14.677Z,1600891034.677 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:15.082Z,1600891035.082 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:15.486Z,1600891035.486 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:15.890Z,1600891035.890 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-23T19:57:15.890Z,1600891035.890 [DAT](FAULT): failed to enter command mode 2020-09-23T19:57:16.294Z,1600891036.294 [DAT](INFO): entering command mode 2020-09-23T19:57:16.699Z,1600891036.699 [DAT](INFO): setting verbose to 3 2020-09-23T19:57:17.103Z,1600891037.103 [DAT](INFO): set verbose to 3 2020-09-23T19:57:17.103Z,1600891037.103 [DAT](INFO): setting DatVerbose to 27440 2020-09-23T19:57:17.512Z,1600891037.512 [DAT](INFO): set DatVerbose to 27440 2020-09-23T19:57:17.513Z,1600891037.513 [DAT](INFO): setting transmit power to 8 2020-09-23T19:57:17.911Z,1600891037.911 [DAT](INFO): set transmit power to 8 2020-09-23T19:57:17.911Z,1600891037.911 [DAT](INFO): setting local address to 9 2020-09-23T19:57:18.315Z,1600891038.315 [DAT](INFO): set local address to 9 2020-09-23T19:57:23.574Z,1600891043.574 [NAL9602](INFO): NAL9602 initialized 2020-09-23T19:57:59.551Z,1600891079.551 [SBIT](IMPORTANT): Beginning Startup BIT 2020-09-23T19:57:59.555Z,1600891079.555 [CBIT](IMPORTANT): Beginning ground fault scan 2020-09-23T19:58:10.492Z,1600891090.492 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006640 CHAN A1 (24V): -0.000929 CHAN A2 (12V): -0.006540 CHAN A3 (5V): -0.002769 CHAN B0 (3.3V): 0.000093 CHAN B1 (3.15aV): -0.000520 CHAN B2 (3.15bV): 0.000257 CHAN B3 (GND): 0.001727 OPEN: 0.005397 Full Scale Calc: 4.765 mA, -1.589 mA 2020-09-23T19:58:53.283Z,1600891133.283 [SBIT](IMPORTANT): SBIT PASSED 2020-09-23T19:58:53.283Z,1600891133.283 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-09-23T19:58:53.284Z,1600891133.284 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2020-09-23T19:58:53.284Z,1600891133.284 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere; 2020-09-23T19:58:53.284Z,1600891133.284 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2020-09-23T19:58:53.284Z,1600891133.284 [SBIT](IMPORTANT): DAT.surfaceThreshold=5 meter; 2020-09-23T19:58:53.284Z,1600891133.284 [SBIT](IMPORTANT): DAT.verbosity=0 count; 2020-09-23T19:58:53.284Z,1600891133.284 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter; 2020-09-23T19:58:53.284Z,1600891133.284 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent; 2020-09-23T19:58:53.285Z,1600891133.285 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second; 2020-09-23T19:58:53.285Z,1600891133.285 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2020-09-23T19:58:53.285Z,1600891133.285 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2020-09-23T19:58:53.285Z,1600891133.285 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2020-09-23T19:58:53.285Z,1600891133.285 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=146.161014 cubic_centimeter; 2020-09-23T19:58:53.285Z,1600891133.285 [SBIT](IMPORTANT): VerticalControl.massDefault=13.952984 millimeter; 2020-09-23T19:58:53.285Z,1600891133.285 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool; 2020-09-23T19:58:53.685Z,1600891133.685 [MissionManager](IMPORTANT): Started mission Startup 2020-09-23T19:58:53.685Z,1600891133.685 [Startup] Running Loop=1 2020-09-23T19:58:53.685Z,1600891133.685 [Startup](DEBUG): Aggregate::initialize Startup 2020-09-23T19:58:53.685Z,1600891133.685 [Startup:A.GoToSurface] Running Loop=1 2020-09-23T19:58:53.685Z,1600891133.685 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-23T19:58:53.686Z,1600891133.686 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-09-23T19:58:53.686Z,1600891133.686 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-09-23T19:58:53.687Z,1600891133.687 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-09-23T19:58:53.687Z,1600891133.687 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-23T19:58:53.688Z,1600891133.688 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-23T19:58:53.689Z,1600891133.689 [Startup:StartupSatComms] Running Loop=1 2020-09-23T19:58:53.689Z,1600891133.689 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-09-23T19:58:53.689Z,1600891133.689 [Startup:StartupSatComms:A] Running Loop=1 2020-09-23T19:58:54.078Z,1600891134.078 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-09-23T19:59:11.815Z,1600891151.815 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005448 2020-09-23T19:59:44.212Z,1600891184.212 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-09-23T19:59:44.212Z,1600891184.212 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-23T19:59:44.261Z,1600891184.261 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-23T19:59:44.606Z,1600891184.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-23T19:59:44.606Z,1600891184.606 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-09-23T19:59:53.880Z,1600891193.880 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-23T19:58:53.7Z 2020-09-23T19:59:53.880Z,1600891193.880 [Startup:StartupSatComms:A] Stopped 2020-09-23T19:59:53.880Z,1600891193.880 [Startup:StartupSatComms:B] Running Loop=1 2020-09-23T19:59:54.277Z,1600891194.277 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-09-23T20:00:01.352Z,1600891201.352 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200923T191528/Courier0007.lzma 2020-09-23T20:00:02.354Z,1600891202.354 [DataOverHttps](INFO): Moved sent file to Logs/20200923T191528/Courier0007.lzma.bak 2020-09-23T20:00:02.354Z,1600891202.354 [DataOverHttps](INFO): SBD MOMSN=12666337 2020-09-23T20:00:17.789Z,1600891217.789 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20200923T195633/Courier0000.lzma 2020-09-23T20:00:19.123Z,1600891219.123 [DataOverHttps](INFO): Moved sent file to Logs/20200923T195633/Courier0000.lzma.bak 2020-09-23T20:00:19.123Z,1600891219.123 [DataOverHttps](INFO): SBD MOMSN=12666339 2020-09-23T20:00:41.456Z,1600891241.456 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20200923T191528/Express0008.lzma 2020-09-23T20:00:42.454Z,1600891242.454 [DataOverHttps](INFO): Moved sent file to Logs/20200923T191528/Express0008.lzma.bak 2020-09-23T20:00:42.454Z,1600891242.454 [DataOverHttps](INFO): SBD MOMSN=12666343 2020-09-23T20:00:54.097Z,1600891254.097 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-23T19:59:53.9Z 2020-09-23T20:00:54.097Z,1600891254.097 [Startup:StartupSatComms:B] Stopped 2020-09-23T20:00:54.097Z,1600891254.097 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-09-23T20:00:54.098Z,1600891254.098 [Startup:StartupSatComms] Stopped 2020-09-23T20:00:54.098Z,1600891254.098 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-09-23T20:00:54.098Z,1600891254.098 [Startup](INFO): Completed Startup 2020-09-23T20:00:54.099Z,1600891254.099 [MissionManager](INFO): Startup is completed. 2020-09-23T20:00:54.099Z,1600891254.099 [MissionManager](INFO): Uninitializing Mission Startup 2020-09-23T20:00:54.099Z,1600891254.099 [Startup] Stopped 2020-09-23T20:00:54.099Z,1600891254.099 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-09-23T20:00:54.099Z,1600891254.099 [Startup:A.GoToSurface] Stopped 2020-09-23T20:00:54.099Z,1600891254.099 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-23T20:00:54.488Z,1600891254.488 [MissionManager](IMPORTANT): Started mission Default 2020-09-23T20:00:54.489Z,1600891254.489 [Default] Running Loop=1 2020-09-23T20:00:54.489Z,1600891254.489 [Default](DEBUG): Aggregate::initialize Default 2020-09-23T20:00:54.489Z,1600891254.489 [Default:B.GoToSurface] Running Loop=1 2020-09-23T20:00:54.489Z,1600891254.489 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-23T20:00:54.489Z,1600891254.489 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-09-23T20:00:54.489Z,1600891254.489 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-09-23T20:00:54.490Z,1600891254.490 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-09-23T20:00:54.490Z,1600891254.490 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-23T20:00:54.490Z,1600891254.490 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-23T20:00:54.491Z,1600891254.491 [Default:A.Wait] Running Loop=1 2020-09-23T20:00:54.491Z,1600891254.491 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-09-23T20:00:59.363Z,1600891259.363 [DataOverHttps](INFO): Sending 1083 bytes from file Logs/20200923T195633/Express0001.lzma 2020-09-23T20:01:00.362Z,1600891260.362 [DataOverHttps](INFO): Moved sent file to Logs/20200923T195633/Express0001.lzma.bak 2020-09-23T20:01:00.362Z,1600891260.362 [DataOverHttps](INFO): SBD MOMSN=12666346 2020-09-23T20:01:07.805Z,1600891267.805 [Default:A.Wait](INFO): Done Waiting. 2020-09-23T20:01:07.805Z,1600891267.805 [Default:A.Wait] Stopped 2020-09-23T20:01:07.805Z,1600891267.805 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-09-23T20:01:08.215Z,1600891268.215 [Default:CheckIn] Running Loop=1 2020-09-23T20:01:08.215Z,1600891268.215 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-23T20:01:08.215Z,1600891268.215 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-23T20:01:08.613Z,1600891268.613 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-09-23T20:02:26.579Z,1600891346.579 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-09-23T20:02:45.189Z,1600891365.189 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-09-23T20:02:45.189Z,1600891365.189 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-23T20:02:45.199Z,1600891365.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-23T20:02:45.615Z,1600891365.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-23T20:02:45.616Z,1600891365.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-09-23T20:05:36.071Z,1600891536.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200534.00,A,3648.16886,N,12147.28452,W,0.836,250.48,230920,,,A*70 2020-09-23T20:05:36.074Z,1600891536.074 [NAL9602](INFO): GPS fix at 20200923T200534: (36.802814, -121.788075) 2020-09-23T20:05:36.083Z,1600891536.083 [Default:CheckIn:Read_GPS] Stopped 2020-09-23T20:05:36.083Z,1600891536.083 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-23T20:05:36.491Z,1600891536.491 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-09-23T20:05:46.731Z,1600891546.731 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20200923T195633/Courier0004.lzma 2020-09-23T20:05:47.734Z,1600891547.734 [DataOverHttps](INFO): Moved sent file to Logs/20200923T195633/Courier0004.lzma.bak 2020-09-23T20:05:47.734Z,1600891547.734 [DataOverHttps](INFO): SBD MOMSN=12666375 2020-09-23T20:06:07.000Z,1600891567.000 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20200923T195633/Express0005.lzma 2020-09-23T20:06:08.002Z,1600891568.002 [DataOverHttps](INFO): Moved sent file to Logs/20200923T195633/Express0005.lzma.bak 2020-09-23T20:06:08.002Z,1600891568.002 [DataOverHttps](INFO): SBD MOMSN=12666378 2020-09-23T20:06:08.457Z,1600891568.457 [NAL9602](INFO): Not Powering down - fast GPS 2020-09-23T20:06:10.083Z,1600891570.083 [Default:CheckIn:Read_Iridium] Stopped 2020-09-23T20:06:10.083Z,1600891570.083 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-23T20:06:10.083Z,1600891570.083 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-23T20:06:34.345Z,1600891594.345 [CommandLine](IMPORTANT): got command restart system 2020-09-23T20:06:36.404Z,1600891596.404 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:36.404Z,1600891596.404 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:36.605Z,1600891596.605 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-09-23T20:06:36.605Z,1600891596.605 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:36.606Z,1600891596.606 [CommandLine](INFO): Join timeout helper Thread ID is 1671 2020-09-23T20:06:36.607Z,1600891596.607 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-09-23T20:06:36.607Z,1600891596.607 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:36.608Z,1600891596.608 [NavChartDb](INFO): Join timeout helper Thread ID is 1672 2020-09-23T20:06:36.884Z,1600891596.884 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:36.884Z,1600891596.884 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:36.900Z,1600891596.900 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-09-23T20:06:36.900Z,1600891596.900 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:36.900Z,1600891596.900 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1673 2020-09-23T20:06:37.072Z,1600891597.072 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:37.072Z,1600891597.072 [WetLabsBB2FL](INFO): Powering down 2020-09-23T20:06:37.073Z,1600891597.073 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:37.092Z,1600891597.092 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-09-23T20:06:37.092Z,1600891597.092 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:37.092Z,1600891597.092 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1674 2020-09-23T20:06:37.484Z,1600891597.484 [CTD_Seabird](INFO): Powering down 2020-09-23T20:06:37.496Z,1600891597.496 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:37.496Z,1600891597.496 [CTD_Seabird](INFO): Powering down 2020-09-23T20:06:37.508Z,1600891597.508 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:37.520Z,1600891597.520 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-09-23T20:06:37.520Z,1600891597.520 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:37.520Z,1600891597.520 [Radio_Surface](INFO): Join timeout helper Thread ID is 1675 2020-09-23T20:06:37.616Z,1600891597.616 [Radio_Surface](INFO): Powering down 2020-09-23T20:06:37.617Z,1600891597.617 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:37.617Z,1600891597.617 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:37.633Z,1600891597.633 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-09-23T20:06:37.633Z,1600891597.633 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:37.633Z,1600891597.633 [Onboard](INFO): Join timeout helper Thread ID is 1676 2020-09-23T20:06:37.804Z,1600891597.804 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-09-23T20:06:39.524Z,1600891599.524 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:39.524Z,1600891599.524 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.533Z,1600891599.533 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-09-23T20:06:39.533Z,1600891599.533 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.533Z,1600891599.533 [DataOverHttps](INFO): Join timeout helper Thread ID is 1677 2020-09-23T20:06:39.812Z,1600891599.812 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:39.812Z,1600891599.812 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.817Z,1600891599.817 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2020-09-23T20:06:39.817Z,1600891599.817 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.817Z,1600891599.817 [BackseatComponent](INFO): Join timeout helper Thread ID is 1678 2020-09-23T20:06:39.848Z,1600891599.848 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:39.848Z,1600891599.848 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.857Z,1600891599.857 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-09-23T20:06:39.857Z,1600891599.857 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.857Z,1600891599.857 [logger](INFO): Join timeout helper Thread ID is 1679 2020-09-23T20:06:39.892Z,1600891599.892 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:39.892Z,1600891599.892 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.897Z,1600891599.897 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-09-23T20:06:39.897Z,1600891599.897 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.897Z,1600891599.897 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-09-23T20:06:39.897Z,1600891599.897 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:39.897Z,1600891599.897 [controlThread](INFO): Join timeout helper Thread ID is 1680 2020-09-23T20:06:39.948Z,1600891599.948 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-23T20:06:39.948Z,1600891599.948 [controlThread](DEBUG): Uninitializing ControlThread 2020-09-23T20:06:39.949Z,1600891599.949 [AHRS_M2](INFO): Powering down 2020-09-23T20:06:40.021Z,1600891600.021 [NAL9602](INFO): Powering down 2020-09-23T20:06:40.022Z,1600891600.022 [DAT](INFO): Powering down 2020-09-23T20:06:40.140Z,1600891600.140 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-09-23T20:06:40.141Z,1600891600.141 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-09-23T20:06:40.142Z,1600891600.142 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-09-23T20:06:40.142Z,1600891600.142 [MissionManager](INFO): Uninitializing Mission Default 2020-09-23T20:06:40.142Z,1600891600.142 [Default] Stopped 2020-09-23T20:06:40.142Z,1600891600.142 [Default](DEBUG): Aggregate::uninitialize Default 2020-09-23T20:06:40.142Z,1600891600.142 [Default:B.GoToSurface] Stopped 2020-09-23T20:06:40.143Z,1600891600.143 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-23T20:06:40.143Z,1600891600.143 [Default:CheckIn] Stopped 2020-09-23T20:06:40.143Z,1600891600.143 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-23T20:06:40.143Z,1600891600.143 [Default:CheckIn:C.Wait] Stopped 2020-09-23T20:06:40.143Z,1600891600.143 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-23T20:06:40.146Z,1600891600.146 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-09-23T20:06:40.146Z,1600891600.146 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-09-23T20:06:40.147Z,1600891600.147 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-09-23T20:06:40.147Z,1600891600.147 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-09-23T20:06:40.147Z,1600891600.147 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-09-23T20:06:40.147Z,1600891600.147 [BuoyancyServo](INFO): Powering down 2020-09-23T20:06:40.160Z,1600891600.160 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-09-23T20:06:40.160Z,1600891600.160 [ElevatorServo](INFO): Powering down 2020-09-23T20:06:40.161Z,1600891600.161 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-09-23T20:06:40.161Z,1600891600.161 [MassServo](INFO): Powering down 2020-09-23T20:06:40.162Z,1600891600.162 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-09-23T20:06:40.162Z,1600891600.162 [RudderServo](INFO): Powering down 2020-09-23T20:06:40.163Z,1600891600.163 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-09-23T20:06:40.163Z,1600891600.163 [ThrusterServo](INFO): Powering down 2020-09-23T20:06:40.164Z,1600891600.164 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-09-23T20:06:40.164Z,1600891600.164 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-09-23T20:06:40.164Z,1600891600.164 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-09-23T20:06:40.165Z,1600891600.165 [CBIT](DEBUG): Powering off loads. 2020-09-23T20:06:40.176Z,1600891600.176 [CBIT](DEBUG): Disabling WDT. 2020-09-23T20:06:40.188Z,1600891600.188 [CBIT](DEBUG): Opening all GF detection circuits. 2020-09-23T20:06:40.189Z,1600891600.189 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.245Z,1600891600.245 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.247Z,1600891600.247 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.254Z,1600891600.254 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.304Z,1600891600.304 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.362Z,1600891600.362 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.365Z,1600891600.365 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.373Z,1600891600.373 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-23T20:06:40.455Z,1600891600.455 [logger ThreadHandler](INFO): Thread cancelled.