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.