2021-04-12T04:00:13.045Z,1618200013.045 [Supervisor](DEBUG): Initializing supervisor.
2021-04-12T04:00:13.049Z,1618200013.049 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-12T04:00:13.050Z,1618200013.050 [SyncHandler](INFO): Protected caller Thread ID is 819
2021-04-12T04:00:13.050Z,1618200013.050 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-04-12T04:00:13.051Z,1618200013.051 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-04-12T04:00:13.052Z,1618200013.052 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 820
2021-04-12T04:00:13.056Z,1618200013.056 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-04-12T04:00:13.075Z,1618200013.075 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-04-12T04:00:13.076Z,1618200013.076 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-04-12T04:00:13.077Z,1618200013.077 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 821
2021-04-12T04:00:13.079Z,1618200013.079 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-04-12T04:00:13.080Z,1618200013.080 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-04-12T04:00:13.080Z,1618200013.080 [logger ThreadHandler](INFO): Protected caller Thread ID is 822
2021-04-12T04:00:13.084Z,1618200013.084 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-04-12T04:00:13.084Z,1618200013.084 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-04-12T04:00:13.089Z,1618200013.089 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-04-12T04:00:13.463Z,1618200013.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-04-12T04:00:13.465Z,1618200013.465 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-04-12T04:00:14.141Z,1618200014.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-04-12T04:00:14.143Z,1618200014.143 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-04-12T04:00:14.247Z,1618200014.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-04-12T04:00:14.248Z,1618200014.248 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-04-12T04:00:14.811Z,1618200014.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-04-12T04:00:14.813Z,1618200014.813 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-04-12T04:00:14.913Z,1618200014.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-04-12T04:00:14.913Z,1618200014.913 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-04-12T04:00:14.996Z,1618200014.996 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-04-12T04:00:15.387Z,1618200015.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-04-12T04:00:15.389Z,1618200015.389 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-04-12T04:00:15.560Z,1618200015.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-04-12T04:00:15.560Z,1618200015.560 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-04-12T04:00:15.692Z,1618200015.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-04-12T04:00:15.693Z,1618200015.693 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-04-12T04:00:15.946Z,1618200015.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-04-12T04:00:15.946Z,1618200015.946 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-04-12T04:00:16.434Z,1618200016.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-04-12T04:00:16.436Z,1618200016.436 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-04-12T04:00:17.056Z,1618200017.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-04-12T04:00:17.058Z,1618200017.058 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-04-12T04:00:17.139Z,1618200017.139 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-04-12T04:00:17.352Z,1618200017.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-04-12T04:00:17.353Z,1618200017.353 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-04-12T04:00:17.577Z,1618200017.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-04-12T04:00:17.579Z,1618200017.579 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-04-12T04:00:17.846Z,1618200017.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-04-12T04:00:17.848Z,1618200017.848 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2021-04-12T04:00:17.851Z,1618200017.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2021-04-12T04:00:17.953Z,1618200017.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2021-04-12T04:00:18.107Z,1618200018.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2021-04-12T04:00:18.195Z,1618200018.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2021-04-12T04:00:18.280Z,1618200018.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2021-04-12T04:00:18.393Z,1618200018.393 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2021-04-12T04:00:18.592Z,1618200018.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2021-04-12T04:00:18.864Z,1618200018.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-04-12T04:00:18.865Z,1618200018.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2021-04-12T04:00:18.994Z,1618200018.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2021-04-12T04:00:19.093Z,1618200019.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2021-04-12T04:00:19.205Z,1618200019.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2021-04-12T04:00:19.302Z,1618200019.302 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-04-12T04:00:19.318Z,1618200019.318 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-04-12T04:00:19.430Z,1618200019.430 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-04-12T04:00:19.432Z,1618200019.432 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-04-12T04:00:19.448Z,1618200019.448 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-04-12T04:00:19.449Z,1618200019.449 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-04-12T04:00:19.508Z,1618200019.508 [DepthRateCalculator] Loaded
2021-04-12T04:00:19.508Z,1618200019.508 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-04-12T04:00:19.513Z,1618200019.513 [PitchRateCalculator] Loaded
2021-04-12T04:00:19.514Z,1618200019.514 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-04-12T04:00:19.524Z,1618200019.524 [SpeedCalculator] Loaded
2021-04-12T04:00:19.525Z,1618200019.525 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-04-12T04:00:19.538Z,1618200019.538 [TempGradientCalculator] Loaded
2021-04-12T04:00:19.538Z,1618200019.538 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-04-12T04:00:19.543Z,1618200019.543 [YawRateCalculator] Loaded
2021-04-12T04:00:19.544Z,1618200019.544 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-04-12T04:00:19.564Z,1618200019.564 [ElevatorOffsetCalculator] Loaded
2021-04-12T04:00:19.565Z,1618200019.565 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-04-12T04:00:19.565Z,1618200019.565 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-04-12T04:00:19.566Z,1618200019.566 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-04-12T04:00:19.637Z,1618200019.637 [VerticalControl](DEBUG): Construct VerticalControl.
2021-04-12T04:00:19.695Z,1618200019.695 [VerticalControl] Loaded
2021-04-12T04:00:19.696Z,1618200019.696 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-04-12T04:00:19.698Z,1618200019.698 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-04-12T04:00:19.737Z,1618200019.737 [HorizontalControl] Loaded
2021-04-12T04:00:19.737Z,1618200019.737 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-04-12T04:00:19.740Z,1618200019.740 [SpeedControl](DEBUG): Construct SpeedControl.
2021-04-12T04:00:19.742Z,1618200019.742 [SpeedControl] Loaded
2021-04-12T04:00:19.743Z,1618200019.743 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-04-12T04:00:19.745Z,1618200019.745 [LoopControl](DEBUG): Construct LoopControl.
2021-04-12T04:00:19.746Z,1618200019.746 [LoopControl] Loaded
2021-04-12T04:00:19.746Z,1618200019.746 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-04-12T04:00:19.746Z,1618200019.746 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-04-12T04:00:19.748Z,1618200019.748 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-04-12T04:00:19.848Z,1618200019.848 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-04-12T04:00:19.848Z,1618200019.848 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-04-12T04:00:19.861Z,1618200019.861 [NavChart] Loaded
2021-04-12T04:00:19.861Z,1618200019.861 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-04-12T04:00:19.867Z,1618200019.867 [UniversalFixResidualReporter] Loaded
2021-04-12T04:00:19.868Z,1618200019.868 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-04-12T04:00:19.868Z,1618200019.868 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-04-12T04:00:19.869Z,1618200019.869 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-04-12T04:00:19.000Z,1618200020.000 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-04-12T04:00:20.000Z,1618200020.000 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-04-12T04:00:20.746Z,1618200020.746 [AHRS_M2] Loaded
2021-04-12T04:00:20.746Z,1618200020.746 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-04-12T04:00:20.990Z,1618200020.990 [BackseatComponent] Loaded
2021-04-12T04:00:20.990Z,1618200020.990 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-04-12T04:00:20.991Z,1618200020.991 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408674E0
2021-04-12T04:00:20.991Z,1618200020.991 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 904
2021-04-12T04:00:20.994Z,1618200020.994 [LcmUniversalReporter] Loaded
2021-04-12T04:00:20.994Z,1618200020.994 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-04-12T04:00:21.783Z,1618200021.783 [BPC1] Loaded
2021-04-12T04:00:21.783Z,1618200021.783 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-04-12T04:00:21.000Z,1618200022.000 [DataOverHttps] Loaded
2021-04-12T04:00:21.000Z,1618200022.000 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-04-12T04:00:22.001Z,1618200022.001 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408974E0
2021-04-12T04:00:22.001Z,1618200022.001 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 905
2021-04-12T04:00:22.117Z,1618200022.117 [Depth_Keller] Loaded
2021-04-12T04:00:22.117Z,1618200022.117 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-04-12T04:00:22.122Z,1618200022.122 [DropWeight] Loaded
2021-04-12T04:00:22.129Z,1618200022.129 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-04-12T04:00:22.195Z,1618200022.195 [DVL_micro] Loaded
2021-04-12T04:00:22.196Z,1618200022.196 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2021-04-12T04:00:22.259Z,1618200022.259 [NAL9602] Loaded
2021-04-12T04:00:22.259Z,1618200022.259 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-04-12T04:00:22.289Z,1618200022.289 [Onboard] Loaded
2021-04-12T04:00:22.289Z,1618200022.289 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-04-12T04:00:22.290Z,1618200022.290 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C74E0
2021-04-12T04:00:22.290Z,1618200022.290 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 906
2021-04-12T04:00:22.304Z,1618200022.304 [Radio_Surface] Loaded
2021-04-12T04:00:22.304Z,1618200022.304 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-04-12T04:00:22.305Z,1618200022.305 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F74E0
2021-04-12T04:00:22.305Z,1618200022.305 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 907
2021-04-12T04:00:22.448Z,1618200022.448 [DAT] Loaded
2021-04-12T04:00:22.449Z,1618200022.449 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-04-12T04:00:22.449Z,1618200022.449 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-04-12T04:00:22.450Z,1618200022.450 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-04-12T04:00:22.586Z,1618200022.586 [BuoyancyServo] Loaded
2021-04-12T04:00:22.587Z,1618200022.587 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-04-12T04:00:22.606Z,1618200022.606 [ElevatorServo] Loaded
2021-04-12T04:00:22.607Z,1618200022.607 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-04-12T04:00:22.626Z,1618200022.626 [MassServo] Loaded
2021-04-12T04:00:22.626Z,1618200022.626 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-04-12T04:00:22.645Z,1618200022.645 [RudderServo] Loaded
2021-04-12T04:00:22.645Z,1618200022.645 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-04-12T04:00:22.663Z,1618200022.663 [ThrusterServo] Loaded
2021-04-12T04:00:22.663Z,1618200022.663 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-04-12T04:00:22.664Z,1618200022.664 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-04-12T04:00:22.664Z,1618200022.664 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-04-12T04:00:22.743Z,1618200022.743 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-04-12T04:00:22.744Z,1618200022.744 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-04-12T04:00:22.787Z,1618200022.787 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-04-12T04:00:22.788Z,1618200022.788 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-04-12T04:00:23.162Z,1618200023.162 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-04-12T04:00:23.162Z,1618200023.162 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-04-12T04:00:23.384Z,1618200023.384 [CTD_Seabird] Loaded
2021-04-12T04:00:23.384Z,1618200023.384 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-04-12T04:00:23.385Z,1618200023.385 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2021-04-12T04:00:23.386Z,1618200023.386 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 908
2021-04-12T04:00:23.418Z,1618200023.418 [ESPComponent] Loaded
2021-04-12T04:00:23.419Z,1618200023.419 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2021-04-12T04:00:23.438Z,1618200023.438 [PAR_Licor] Loaded
2021-04-12T04:00:23.439Z,1618200023.439 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-04-12T04:00:23.471Z,1618200023.471 [WetLabsBB2FL] Loaded
2021-04-12T04:00:23.471Z,1618200023.471 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-04-12T04:00:23.472Z,1618200023.472 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2021-04-12T04:00:23.473Z,1618200023.473 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 909
2021-04-12T04:00:23.473Z,1618200023.473 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-04-12T04:00:23.475Z,1618200023.475 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-04-12T04:00:23.649Z,1618200023.649 [SBIT](DEBUG): Construct Startup Built In Test.
2021-04-12T04:00:23.658Z,1618200023.658 [SBIT] Loaded
2021-04-12T04:00:23.659Z,1618200023.659 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-04-12T04:00:23.661Z,1618200023.661 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-04-12T04:00:23.675Z,1618200023.675 [IBIT] Loaded
2021-04-12T04:00:23.675Z,1618200023.675 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-04-12T04:00:23.681Z,1618200023.681 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-04-12T04:00:23.785Z,1618200023.785 [CBIT] Loaded
2021-04-12T04:00:23.785Z,1618200023.785 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-04-12T04:00:23.786Z,1618200023.786 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-04-12T04:00:23.792Z,1618200023.792 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-04-12T04:00:23.795Z,1618200023.795 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-04-12T04:00:23.805Z,1618200023.805 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-04-12T04:00:23.806Z,1618200023.806 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B1B4E0
2021-04-12T04:00:23.807Z,1618200023.807 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 910
2021-04-12T04:00:23.811Z,1618200023.811 [Supervisor](INFO): Main Thread ID is 815
2021-04-12T04:00:23.811Z,1618200023.811 [Supervisor](DEBUG): Running supervisor.
2021-04-12T04:00:23.812Z,1618200023.812 [CommandLine ThreadHandler](INFO): Handler Thread ID is 911
2021-04-12T04:00:23.814Z,1618200023.814 [controlThread ThreadHandler](INFO): Handler Thread ID is 912
2021-04-12T04:00:23.815Z,1618200023.815 [controlThread](DEBUG): Initializing ControlThread
2021-04-12T04:00:23.816Z,1618200023.816 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-04-12T04:00:23.816Z,1618200023.816 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-04-12T04:00:23.816Z,1618200023.816 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-04-12T04:00:23.817Z,1618200023.817 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-04-12T04:00:23.817Z,1618200023.817 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-04-12T04:00:23.818Z,1618200023.818 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-04-12T04:00:23.818Z,1618200023.818 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-04-12T04:00:23.820Z,1618200023.820 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-04-12T04:00:23.821Z,1618200023.821 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-04-12T04:00:23.821Z,1618200023.821 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-04-12T04:00:23.822Z,1618200023.822 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-04-12T04:00:23.823Z,1618200023.823 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-04-12T04:00:23.831Z,1618200023.831 [SBIT](INFO): Initialize SBIT Component.
2021-04-12T04:00:23.832Z,1618200023.832 [SBIT](IMPORTANT): git: 2021-04-08-2-gfc5b6cfe3
2021-04-12T04:00:23.832Z,1618200023.832 [SBIT](INFO): git hash: fc5b6cfe3a0a617c2655bbe510b7fa07d5046659
2021-04-12T04:00:23.832Z,1618200023.832 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-04-12T04:00:23.832Z,1618200023.832 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
2021-04-12T04:00:23.834Z,1618200023.833 [SBIT](INFO): Beginning SBIT in 22.000000 seconds.
2021-04-12T04:00:23.834Z,1618200023.834 [IBIT](INFO): Initialize IBIT Component.
2021-04-12T04:00:23.835Z,1618200023.835 [CBIT](DEBUG): Initialize CBIT Component.
2021-04-12T04:00:23.836Z,1618200023.836 [logger ThreadHandler](INFO): Handler Thread ID is 913
2021-04-12T04:00:23.847Z,1618200023.847 [CBIT](DEBUG): Initialized mux pins.
2021-04-12T04:00:23.847Z,1618200023.847 [CBIT](DEBUG): Initializing the watchdog timer.
2021-04-12T04:00:23.855Z,1618200023.855 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 914
2021-04-12T04:00:23.867Z,1618200023.867 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 915
2021-04-12T04:00:23.868Z,1618200023.868 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-04-12T04:00:23.871Z,1618200023.871 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-04-12T04:00:23.871Z,1618200023.871 [CBIT](DEBUG): Initializing heartbeat.
2021-04-12T04:00:23.879Z,1618200023.879 [Onboard ThreadHandler](INFO): Handler Thread ID is 916
2021-04-12T04:00:23.896Z,1618200023.896 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 917
2021-04-12T04:00:23.915Z,1618200023.915 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 918
2021-04-12T04:00:23.916Z,1618200023.916 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-12T04:00:23.920Z,1618200023.920 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 920
2021-04-12T04:00:23.923Z,1618200023.923 [WetLabsBB2FL](INFO): Powering up
2021-04-12T04:00:23.924Z,1618200023.924 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 922
2021-04-12T04:00:23.933Z,1618200023.933 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-04-12T04:00:23.933Z,1618200023.934 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-04-12T04:00:23.934Z,1618200023.934 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-04-12T04:00:23.934Z,1618200023.934 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-04-12T04:00:23.934Z,1618200023.934 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-04-12T04:00:23.934Z,1618200023.934 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-04-12T04:00:23.934Z,1618200023.934 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-04-12T04:00:23.935Z,1618200023.935 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-04-12T04:00:23.935Z,1618200023.935 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-04-12T04:00:23.935Z,1618200023.935 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-04-12T04:00:23.935Z,1618200023.935 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-04-12T04:00:23.935Z,1618200023.935 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-04-12T04:00:23.936Z,1618200023.936 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-04-12T04:00:23.936Z,1618200023.936 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-04-12T04:00:23.936Z,1618200023.936 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-04-12T04:00:23.936Z,1618200023.936 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-04-12T04:00:23.943Z,1618200023.943 [CBIT](DEBUG): Deactivating GF circuits.
2021-04-12T04:00:23.943Z,1618200023.943 [CBIT](DEBUG): Deactivating emergency mode.
2021-04-12T04:00:23.979Z,1618200023.979 [CBIT](DEBUG): Backplane powered.
2021-04-12T04:00:23.980Z,1618200023.980 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-04-12T04:00:24.006Z,1618200024.006 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-12T04:00:24.033Z,1618200024.033 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2021-04-12T04:00:24.034Z,1618200024.034 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-04-12T04:00:24.121Z,1618200024.121 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-04-12T04:00:24.122Z,1618200024.122 [Default:A.Wait](DEBUG): Construct Wait.
2021-04-12T04:00:24.124Z,1618200024.124 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-12T04:00:24.176Z,1618200024.176 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-04-12T04:00:24.179Z,1618200024.179 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-04-12T04:00:24.201Z,1618200024.201 [Default:E.Execute](DEBUG): Construct Execute.
2021-04-12T04:00:24.204Z,1618200024.204 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2021-04-12T04:00:24.223Z,1618200024.223 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-04-12T04:00:24.227Z,1618200024.227 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-04-12T04:00:24.330Z,1618200024.330 [Radio_Surface](INFO): Powering up
2021-04-12T04:00:24.502Z,1618200024.502 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-04-12T04:00:24.502Z,1618200024.502 [DAT](INFO): Powering up
2021-04-12T04:00:24.503Z,1618200024.503 [DAT](DEBUG): Initializing DAT.
2021-04-12T04:00:24.516Z,1618200024.516 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-04-12T04:00:24.629Z,1618200024.629 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:00:24.639Z,1618200024.639 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-12T04:00:24.640Z,1618200024.640 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:00:24.651Z,1618200024.651 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-04-12T04:00:24.652Z,1618200024.652 [MassServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:00:24.663Z,1618200024.663 [MassServo](DEBUG): Initializing MassServo.
2021-04-12T04:00:24.664Z,1618200024.664 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:00:24.675Z,1618200024.675 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-12T04:00:24.676Z,1618200024.676 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-04-12T04:00:24.683Z,1618200024.683 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-04-12T04:00:26.535Z,1618200026.535 [WetLabsBB2FL](INFO): Powering down
2021-04-12T04:00:30.947Z,1618200030.947 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-12T04:00:38.843Z,1618200038.843 [DAT](INFO): commRate: 800
2021-04-12T04:00:40.861Z,1618200040.861 [DAT](INFO): entering command mode
2021-04-12T04:00:41.265Z,1618200041.265 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:41.674Z,1618200041.674 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:42.082Z,1618200042.082 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:42.477Z,1618200042.477 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:42.882Z,1618200042.882 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:43.285Z,1618200043.285 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:43.689Z,1618200043.689 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:44.093Z,1618200044.093 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:44.506Z,1618200044.506 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:44.902Z,1618200044.902 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:45.305Z,1618200045.305 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:45.709Z,1618200045.709 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:46.113Z,1618200046.113 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:46.517Z,1618200046.517 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:46.542Z,1618200046.542 [SBIT](IMPORTANT): Beginning Startup BIT
2021-04-12T04:00:46.554Z,1618200046.554 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-12T04:00:46.935Z,1618200046.935 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:47.325Z,1618200047.325 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:47.729Z,1618200047.729 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:48.133Z,1618200048.133 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:48.537Z,1618200048.537 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:48.941Z,1618200048.941 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:49.345Z,1618200049.345 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:49.758Z,1618200049.758 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:50.162Z,1618200050.162 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:50.557Z,1618200050.557 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:50.961Z,1618200050.961 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:51.365Z,1618200051.365 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:51.775Z,1618200051.775 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:52.182Z,1618200052.182 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:52.577Z,1618200052.577 [NAL9602](INFO): Powering up NAL9602
2021-04-12T04:00:52.578Z,1618200052.578 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:52.981Z,1618200052.981 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:53.390Z,1618200053.390 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:53.789Z,1618200053.789 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:54.201Z,1618200054.201 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:54.606Z,1618200054.606 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:55.001Z,1618200055.001 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:55.405Z,1618200055.405 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:55.809Z,1618200055.809 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:56.213Z,1618200056.213 [DAT](DEBUG): checking for command mode acknowledgment
2021-04-12T04:00:56.213Z,1618200056.213 [DAT](FAULT): failed to enter command mode
2021-04-12T04:00:56.623Z,1618200056.623 [DAT](INFO): entering command mode
2021-04-12T04:00:57.023Z,1618200057.023 [DAT](INFO): setting verbose to 3
2021-04-12T04:00:57.435Z,1618200057.435 [DAT](INFO): set verbose to 3
2021-04-12T04:00:57.436Z,1618200057.436 [DAT](INFO): setting DatVerbose to 27440
2021-04-12T04:00:57.544Z,1618200057.544 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003308
CHAN A1 (24V): -0.002360
CHAN A2 (12V): -0.000143
CHAN A3 (5V): -0.000002
CHAN B0 (3.3V): 0.000639
CHAN B1 (3.15aV): 0.000668
CHAN B2 (3.15bV): 0.000439
CHAN B3 (GND): 0.001006
OPEN: -0.004484
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-12T04:00:57.830Z,1618200057.830 [DAT](INFO): set DatVerbose to 27440
2021-04-12T04:00:57.830Z,1618200057.830 [DAT](INFO): setting transmit power to 8
2021-04-12T04:00:58.234Z,1618200058.234 [DAT](INFO): set transmit power to 8
2021-04-12T04:00:58.235Z,1618200058.235 [DAT](INFO): setting local address to 8
2021-04-12T04:00:58.643Z,1618200058.643 [DAT](INFO): set local address to 8
2021-04-12T04:01:03.501Z,1618200063.501 [NAL9602](INFO): NAL9602 initialized
2021-04-12T04:01:29.352Z,1618200089.352 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:01:40.684Z,1618200100.684 [SBIT](IMPORTANT): SBIT PASSED
2021-04-12T04:01:40.684Z,1618200100.684 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-12T04:01:40.685Z,1618200100.685 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-04-12T04:01:40.685Z,1618200100.685 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-04-12T04:01:40.685Z,1618200100.685 [SBIT](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2021-04-12T04:01:40.685Z,1618200100.685 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2021-04-12T04:01:40.685Z,1618200100.685 [SBIT](IMPORTANT): DAT.surfaceThreshold=-0.5 meter;

2021-04-12T04:01:40.685Z,1618200100.685 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2021-04-12T04:01:40.685Z,1618200100.685 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=1 minute;

2021-04-12T04:01:40.686Z,1618200100.686 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2021-04-12T04:01:40.686Z,1618200100.686 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=194.169302 cubic_centimeter;

2021-04-12T04:01:40.686Z,1618200100.686 [SBIT](IMPORTANT): VerticalControl.massDefault=-0.743969 millimeter;

2021-04-12T04:01:41.126Z,1618200101.126 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:01:41.157Z,1618200101.157 [MissionManager](IMPORTANT): Started mission Startup
2021-04-12T04:01:41.157Z,1618200101.157 [Startup] Running Loop=1
2021-04-12T04:01:41.157Z,1618200101.157 [Startup](DEBUG): Aggregate::initialize Startup
2021-04-12T04:01:41.158Z,1618200101.158 [Startup:A.GoToSurface] Running Loop=1
2021-04-12T04:01:41.158Z,1618200101.158 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-12T04:01:41.158Z,1618200101.158 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-12T04:01:41.159Z,1618200101.159 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-12T04:01:41.159Z,1618200101.159 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-12T04:01:41.160Z,1618200101.160 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-12T04:01:41.160Z,1618200101.160 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-12T04:01:41.161Z,1618200101.161 [Startup:StartupSatComms] Running Loop=1
2021-04-12T04:01:41.161Z,1618200101.161 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-04-12T04:01:41.162Z,1618200101.162 [Startup:StartupSatComms:A] Running Loop=1
2021-04-12T04:01:41.510Z,1618200101.510 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-04-12T04:01:52.840Z,1618200112.840 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:02:04.969Z,1618200124.969 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:02:16.675Z,1618200136.675 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:02:27.115Z,1618200147.115 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:02:27.119Z,1618200147.119 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:02:38.897Z,1618200158.897 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:02:41.265Z,1618200161.265 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-12T04:01:41.2Z
2021-04-12T04:02:41.265Z,1618200161.265 [Startup:StartupSatComms:A] Stopped
2021-04-12T04:02:41.266Z,1618200161.266 [Startup:StartupSatComms:B] Running Loop=1
2021-04-12T04:02:41.680Z,1618200161.680 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-12T04:02:51.024Z,1618200171.024 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:02:52.781Z,1618200172.781 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004752
2021-04-12T04:02:59.980Z,1618200179.980 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210412T034815/Courier0000.lzma
2021-04-12T04:03:00.981Z,1618200180.981 [DataOverHttps](INFO): Moved sent file to Logs/20210412T034815/Courier0000.lzma.bak
2021-04-12T04:03:00.981Z,1618200180.981 [DataOverHttps](INFO): SBD MOMSN=15558579
2021-04-12T04:03:01.878Z,1618200181.878 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2021-04-12T04:03:01.882Z,1618200181.882 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:03:13.703Z,1618200193.703 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:03:16.704Z,1618200196.704 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210412T034901/Courier0000.lzma
2021-04-12T04:03:17.708Z,1618200197.708 [DataOverHttps](INFO): Moved sent file to Logs/20210412T034901/Courier0000.lzma.bak
2021-04-12T04:03:17.709Z,1618200197.709 [DataOverHttps](INFO): SBD MOMSN=15558582
2021-04-12T04:03:24.119Z,1618200204.119 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-04-12T04:03:24.119Z,1618200204.119 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-04-12T04:03:24.150Z,1618200204.150 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-04-12T04:03:24.529Z,1618200204.529 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-04-12T04:03:24.529Z,1618200204.529 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-04-12T04:03:24.971Z,1618200204.971 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:03:33.162Z,1618200213.162 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210412T034901/Courier0004.lzma
2021-04-12T04:03:34.164Z,1618200214.164 [DataOverHttps](INFO): Moved sent file to Logs/20210412T034901/Courier0004.lzma.bak
2021-04-12T04:03:34.165Z,1618200214.165 [DataOverHttps](INFO): SBD MOMSN=15558585
2021-04-12T04:03:37.101Z,1618200217.101 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2021-04-12T04:03:41.493Z,1618200221.493 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-12T04:02:41.3Z
2021-04-12T04:03:41.494Z,1618200221.494 [Startup:StartupSatComms:B] Stopped
2021-04-12T04:03:41.494Z,1618200221.494 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-04-12T04:03:41.494Z,1618200221.494 [Startup:StartupSatComms] Stopped
2021-04-12T04:03:41.494Z,1618200221.494 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-04-12T04:03:41.495Z,1618200221.495 [Startup](INFO): Completed Startup
2021-04-12T04:03:41.495Z,1618200221.495 [MissionManager](INFO): Startup is completed.
2021-04-12T04:03:41.495Z,1618200221.495 [MissionManager](INFO): Uninitializing Mission Startup
2021-04-12T04:03:41.495Z,1618200221.495 [Startup] Stopped
2021-04-12T04:03:41.495Z,1618200221.495 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-04-12T04:03:41.495Z,1618200221.495 [Startup:A.GoToSurface] Stopped
2021-04-12T04:03:41.495Z,1618200221.495 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-12T04:03:41.903Z,1618200221.903 [MissionManager](IMPORTANT): Started mission Default
2021-04-12T04:03:41.903Z,1618200221.903 [Default] Running Loop=1
2021-04-12T04:03:41.904Z,1618200221.904 [Default](DEBUG): Aggregate::initialize Default
2021-04-12T04:03:41.904Z,1618200221.904 [Default:B.GoToSurface] Running Loop=1
2021-04-12T04:03:41.904Z,1618200221.904 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-12T04:03:41.904Z,1618200221.904 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-12T04:03:41.904Z,1618200221.904 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-12T04:03:41.905Z,1618200221.905 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-12T04:03:41.905Z,1618200221.905 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-12T04:03:41.905Z,1618200221.905 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-12T04:03:41.905Z,1618200221.905 [Default:A.Wait] Running Loop=1
2021-04-12T04:03:41.906Z,1618200221.906 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-12T04:03:50.802Z,1618200230.802 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210412T035748/Courier0000.lzma
2021-04-12T04:03:51.804Z,1618200231.804 [DataOverHttps](INFO): Moved sent file to Logs/20210412T035748/Courier0000.lzma.bak
2021-04-12T04:03:51.805Z,1618200231.805 [DataOverHttps](INFO): SBD MOMSN=15558587
2021-04-12T04:03:55.247Z,1618200235.247 [Default:A.Wait](INFO): Done Waiting.
2021-04-12T04:03:55.247Z,1618200235.247 [Default:A.Wait] Stopped
2021-04-12T04:03:55.247Z,1618200235.247 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-12T04:03:55.641Z,1618200235.641 [Default:CheckIn] Running Loop=1
2021-04-12T04:03:55.641Z,1618200235.641 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-12T04:03:55.642Z,1618200235.642 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-12T04:03:56.058Z,1618200236.058 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-12T04:04:33.260Z,1618200273.260 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:05:04.290Z,1618200304.290 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-04-12T04:05:41.580Z,1618200341.580 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2021-04-12T04:05:52.375Z,1618200352.375 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2021-04-12T04:05:52.375Z,1618200352.375 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.4,0000.0,148

2021-04-12T04:05:58.012Z,1618200358.012 [CommandLine](IMPORTANT): got command restart application
2021-04-12T04:05:59.023Z,1618200359.023 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-04-12T04:05:59.023Z,1618200359.023 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:05:59.023Z,1618200359.023 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:05:59.179Z,1618200359.179 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-12T04:05:59.179Z,1618200359.179 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:05:59.180Z,1618200359.180 [CommandLine](INFO): Join timeout helper Thread ID is 964
2021-04-12T04:05:59.180Z,1618200359.180 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-12T04:05:59.180Z,1618200359.180 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:05:59.181Z,1618200359.181 [NavChartDb](INFO): Join timeout helper Thread ID is 965
2021-04-12T04:05:59.571Z,1618200359.571 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:05:59.571Z,1618200359.571 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:05:59.579Z,1618200359.579 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-04-12T04:05:59.579Z,1618200359.579 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:05:59.579Z,1618200359.579 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 966
2021-04-12T04:05:59.711Z,1618200359.711 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:05:59.711Z,1618200359.711 [WetLabsBB2FL](INFO): Powering down
2021-04-12T04:05:59.712Z,1618200359.712 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:05:59.723Z,1618200359.723 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-04-12T04:05:59.723Z,1618200359.723 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:05:59.723Z,1618200359.723 [CTD_Seabird](INFO): Join timeout helper Thread ID is 967
2021-04-12T04:06:00.335Z,1618200360.335 [CTD_Seabird](INFO): Powering down
2021-04-12T04:06:00.347Z,1618200360.347 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:06:00.347Z,1618200360.347 [CTD_Seabird](INFO): Powering down
2021-04-12T04:06:00.359Z,1618200360.359 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:00.363Z,1618200360.363 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-12T04:06:00.363Z,1618200360.363 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:00.363Z,1618200360.363 [Radio_Surface](INFO): Join timeout helper Thread ID is 968
2021-04-12T04:06:00.579Z,1618200360.579 [Radio_Surface](INFO): Powering down
2021-04-12T04:06:00.580Z,1618200360.580 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:06:00.580Z,1618200360.580 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:00.598Z,1618200360.598 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-12T04:06:00.598Z,1618200360.598 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:00.599Z,1618200360.599 [Onboard](INFO): Join timeout helper Thread ID is 969
2021-04-12T04:06:01.503Z,1618200361.503 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-04-12T04:06:04.191Z,1618200364.191 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:06:04.191Z,1618200364.191 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.211Z,1618200364.211 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-12T04:06:04.211Z,1618200364.211 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.212Z,1618200364.212 [DataOverHttps](INFO): Join timeout helper Thread ID is 970
2021-04-12T04:06:04.515Z,1618200364.515 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:06:04.515Z,1618200364.515 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.535Z,1618200364.535 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-04-12T04:06:04.536Z,1618200364.536 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.536Z,1618200364.536 [BackseatComponent](INFO): Join timeout helper Thread ID is 971
2021-04-12T04:06:04.731Z,1618200364.731 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:06:04.731Z,1618200364.731 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.743Z,1618200364.743 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-12T04:06:04.744Z,1618200364.744 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.744Z,1618200364.744 [logger](INFO): Join timeout helper Thread ID is 972
2021-04-12T04:06:04.759Z,1618200364.759 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:06:04.759Z,1618200364.759 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.763Z,1618200364.763 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-12T04:06:04.764Z,1618200364.764 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.764Z,1618200364.764 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-12T04:06:04.764Z,1618200364.764 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:04.764Z,1618200364.764 [controlThread](INFO): Join timeout helper Thread ID is 973
2021-04-12T04:06:04.883Z,1618200364.883 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-12T04:06:04.883Z,1618200364.883 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-12T04:06:04.884Z,1618200364.884 [AHRS_M2](INFO): Powering down
2021-04-12T04:06:05.027Z,1618200365.027 [DVL_micro](INFO): Powering down
2021-04-12T04:06:05.028Z,1618200365.028 [NAL9602](INFO): Powering down
2021-04-12T04:06:05.029Z,1618200365.029 [DAT](INFO): Powering down
2021-04-12T04:06:05.148Z,1618200365.148 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-12T04:06:05.149Z,1618200365.149 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-12T04:06:05.149Z,1618200365.149 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-12T04:06:05.150Z,1618200365.150 [MissionManager](INFO): Uninitializing Mission Default
2021-04-12T04:06:05.150Z,1618200365.150 [Default] Stopped
2021-04-12T04:06:05.150Z,1618200365.150 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-12T04:06:05.150Z,1618200365.150 [Default:B.GoToSurface] Stopped
2021-04-12T04:06:05.150Z,1618200365.150 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-12T04:06:05.150Z,1618200365.150 [Default:CheckIn] Stopped
2021-04-12T04:06:05.150Z,1618200365.150 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-12T04:06:05.150Z,1618200365.150 [Default:CheckIn:Read_GPS] Stopped
2021-04-12T04:06:05.153Z,1618200365.153 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-12T04:06:05.153Z,1618200365.153 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-12T04:06:05.154Z,1618200365.154 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-12T04:06:05.154Z,1618200365.154 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-12T04:06:05.154Z,1618200365.154 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-12T04:06:05.154Z,1618200365.154 [BuoyancyServo](INFO): Powering down
2021-04-12T04:06:05.167Z,1618200365.167 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-12T04:06:05.167Z,1618200365.167 [ElevatorServo](INFO): Powering down
2021-04-12T04:06:05.168Z,1618200365.168 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-12T04:06:05.168Z,1618200365.168 [MassServo](INFO): Powering down
2021-04-12T04:06:05.169Z,1618200365.169 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-12T04:06:05.169Z,1618200365.169 [RudderServo](INFO): Powering down
2021-04-12T04:06:05.170Z,1618200365.170 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-12T04:06:05.170Z,1618200365.170 [ThrusterServo](INFO): Powering down
2021-04-12T04:06:05.171Z,1618200365.171 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-12T04:06:05.171Z,1618200365.171 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-12T04:06:05.171Z,1618200365.171 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-12T04:06:05.171Z,1618200365.171 [CBIT](DEBUG): Powering off loads.
2021-04-12T04:06:05.183Z,1618200365.183 [CBIT](DEBUG): Disabling WDT.
2021-04-12T04:06:05.195Z,1618200365.195 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-12T04:06:05.196Z,1618200365.196 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.246Z,1618200365.246 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.248Z,1618200365.248 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.256Z,1618200365.256 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.300Z,1618200365.300 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.357Z,1618200365.357 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.362Z,1618200365.362 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.387Z,1618200365.387 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-12T04:06:05.448Z,1618200365.448 [logger ThreadHandler](INFO): Thread cancelled.