2019-03-21T22:17:52.832Z,1553206672.832 [Supervisor](DEBUG): Initializing supervisor. 2019-03-21T22:17:52.835Z,1553206672.835 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-03-21T22:17:52.836Z,1553206672.836 [SyncHandler](INFO): Protected caller Thread ID is 1812 2019-03-21T22:17:52.836Z,1553206672.836 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-03-21T22:17:52.837Z,1553206672.837 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-03-21T22:17:52.838Z,1553206672.838 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1813 2019-03-21T22:17:52.841Z,1553206672.841 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-03-21T22:17:52.853Z,1553206672.853 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-03-21T22:17:52.854Z,1553206672.854 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-03-21T22:17:52.854Z,1553206672.854 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1814 2019-03-21T22:17:52.855Z,1553206672.855 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-03-21T22:17:52.856Z,1553206672.856 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-03-21T22:17:52.856Z,1553206672.856 [logger ThreadHandler](INFO): Protected caller Thread ID is 1815 2019-03-21T22:17:52.859Z,1553206672.859 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-03-21T22:17:52.859Z,1553206672.859 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-03-21T22:17:52.860Z,1553206672.860 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-03-21T22:17:53.299Z,1553206673.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-03-21T22:17:53.300Z,1553206673.300 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-03-21T22:17:53.446Z,1553206673.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-03-21T22:17:53.446Z,1553206673.446 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-03-21T22:17:53.526Z,1553206673.526 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-03-21T22:17:53.623Z,1553206673.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-03-21T22:17:53.624Z,1553206673.624 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-03-21T22:17:53.719Z,1553206673.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-03-21T22:17:53.719Z,1553206673.719 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-03-21T22:17:54.100Z,1553206674.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-03-21T22:17:54.101Z,1553206674.101 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-03-21T22:17:54.203Z,1553206674.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-03-21T22:17:54.204Z,1553206674.204 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-03-21T22:17:54.690Z,1553206674.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-03-21T22:17:54.690Z,1553206674.690 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-03-21T22:17:54.919Z,1553206674.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-03-21T22:17:54.919Z,1553206674.919 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-03-21T22:17:55.236Z,1553206675.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-03-21T22:17:55.237Z,1553206675.237 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-03-21T22:17:55.456Z,1553206675.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-03-21T22:17:55.457Z,1553206675.457 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-03-21T22:17:55.601Z,1553206675.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-03-21T22:17:55.602Z,1553206675.602 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-03-21T22:17:56.114Z,1553206676.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-03-21T22:17:56.115Z,1553206676.115 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-03-21T22:17:56.462Z,1553206676.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-03-21T22:17:56.463Z,1553206676.463 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-03-21T22:17:56.675Z,1553206676.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-03-21T22:17:56.676Z,1553206676.676 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2019-03-21T22:17:56.677Z,1553206676.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2019-03-21T22:17:56.856Z,1553206676.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2019-03-21T22:17:56.938Z,1553206676.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2019-03-21T22:17:57.054Z,1553206677.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2019-03-21T22:17:57.139Z,1553206677.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2019-03-21T22:17:57.369Z,1553206677.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-03-21T22:17:57.370Z,1553206677.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2019-03-21T22:17:57.468Z,1553206677.468 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2019-03-21T22:17:57.578Z,1553206677.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2019-03-21T22:17:57.672Z,1553206677.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2019-03-21T22:17:57.827Z,1553206677.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2019-03-21T22:17:57.928Z,1553206677.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2019-03-21T22:17:58.028Z,1553206678.028 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2019-03-21T22:17:58.029Z,1553206678.029 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-03-21T22:17:58.035Z,1553206678.035 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-03-21T22:17:58.048Z,1553206678.048 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-03-21T22:17:58.049Z,1553206678.049 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-03-21T22:17:58.115Z,1553206678.115 [VerticalControl](DEBUG): Construct VerticalControl. 2019-03-21T22:17:58.229Z,1553206678.229 [VerticalControl] Loaded 2019-03-21T22:17:58.229Z,1553206678.229 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-03-21T22:17:58.230Z,1553206678.230 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-03-21T22:17:58.299Z,1553206678.299 [HorizontalControl] Loaded 2019-03-21T22:17:58.299Z,1553206678.299 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-03-21T22:17:58.300Z,1553206678.300 [SpeedControl](DEBUG): Construct SpeedControl. 2019-03-21T22:17:58.305Z,1553206678.305 [SpeedControl] Loaded 2019-03-21T22:17:58.306Z,1553206678.306 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-03-21T22:17:58.306Z,1553206678.306 [LoopControl](DEBUG): Construct LoopControl. 2019-03-21T22:17:58.307Z,1553206678.307 [LoopControl] Loaded 2019-03-21T22:17:58.307Z,1553206678.307 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-03-21T22:17:58.308Z,1553206678.308 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-03-21T22:17:58.308Z,1553206678.308 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-03-21T22:17:58.423Z,1553206678.423 [ElevatorServo] Loaded 2019-03-21T22:17:58.424Z,1553206678.424 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-03-21T22:17:58.435Z,1553206678.435 [MassServo] Loaded 2019-03-21T22:17:58.435Z,1553206678.435 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-03-21T22:17:58.446Z,1553206678.446 [RudderServo] Loaded 2019-03-21T22:17:58.446Z,1553206678.446 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-03-21T22:17:58.447Z,1553206678.447 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-03-21T22:17:58.447Z,1553206678.447 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-03-21T22:17:58.487Z,1553206678.487 [DepthRateCalculator] Loaded 2019-03-21T22:17:58.487Z,1553206678.487 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-03-21T22:17:58.493Z,1553206678.493 [PitchRateCalculator] Loaded 2019-03-21T22:17:58.493Z,1553206678.493 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-03-21T22:17:58.508Z,1553206678.508 [SpeedCalculator] Loaded 2019-03-21T22:17:58.509Z,1553206678.509 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-03-21T22:17:58.530Z,1553206678.530 [TempGradientCalculator] Loaded 2019-03-21T22:17:58.530Z,1553206678.530 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-03-21T22:17:58.535Z,1553206678.535 [YawRateCalculator] Loaded 2019-03-21T22:17:58.535Z,1553206678.535 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-03-21T22:17:58.564Z,1553206678.564 [ElevatorOffsetCalculator] Loaded 2019-03-21T22:17:58.565Z,1553206678.565 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-03-21T22:17:58.565Z,1553206678.565 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-03-21T22:17:58.566Z,1553206678.566 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-03-21T22:17:58.670Z,1553206678.670 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-03-21T22:17:58.670Z,1553206678.670 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-03-21T22:17:58.730Z,1553206678.730 [DeadReckonUsingSpeedCalculator] Loaded 2019-03-21T22:17:58.730Z,1553206678.730 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-03-21T22:17:58.751Z,1553206678.751 [NavChart] Loaded 2019-03-21T22:17:58.751Z,1553206678.751 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-03-21T22:17:58.755Z,1553206678.755 [UniversalFixResidualReporter] Loaded 2019-03-21T22:17:58.755Z,1553206678.755 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-03-21T22:17:58.756Z,1553206678.756 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-03-21T22:17:58.756Z,1553206678.756 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-03-21T22:17:59.068Z,1553206679.068 [DataOverHttps] Loaded 2019-03-21T22:17:59.068Z,1553206679.068 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-03-21T22:17:59.069Z,1553206679.069 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F74E0 2019-03-21T22:17:59.070Z,1553206679.070 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1894 2019-03-21T22:17:59.083Z,1553206679.083 [Depth_Keller] Loaded 2019-03-21T22:17:59.083Z,1553206679.083 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-03-21T22:17:59.088Z,1553206679.088 [DropWeight] Loaded 2019-03-21T22:17:59.089Z,1553206679.089 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-03-21T22:17:59.185Z,1553206679.185 [NAL9602] Loaded 2019-03-21T22:17:59.186Z,1553206679.186 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-03-21T22:17:59.202Z,1553206679.202 [Onboard] Loaded 2019-03-21T22:17:59.202Z,1553206679.202 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-03-21T22:17:59.205Z,1553206679.205 [Radio_Surface] Loaded 2019-03-21T22:17:59.205Z,1553206679.205 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-03-21T22:17:59.206Z,1553206679.206 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408274E0 2019-03-21T22:17:59.207Z,1553206679.207 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1895 2019-03-21T22:18:01.500Z,1553206681.500 [BPC1] Loaded 2019-03-21T22:18:01.501Z,1553206681.501 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-03-21T22:18:01.501Z,1553206681.501 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-03-21T22:18:01.502Z,1553206681.502 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-03-21T22:18:01.565Z,1553206681.565 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-03-21T22:18:01.566Z,1553206681.566 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-03-21T22:18:01.661Z,1553206681.661 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-03-21T22:18:01.661Z,1553206681.661 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-03-21T22:18:01.687Z,1553206681.687 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-03-21T22:18:01.688Z,1553206681.688 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-03-21T22:18:01.934Z,1553206681.934 [CTD_NeilBrown] Loaded 2019-03-21T22:18:01.935Z,1553206681.935 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-03-21T22:18:01.936Z,1553206681.936 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409B34E0 2019-03-21T22:18:01.936Z,1553206681.936 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1896 2019-03-21T22:18:01.946Z,1553206681.946 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-03-21T22:18:01.951Z,1553206681.951 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-03-21T22:18:01.952Z,1553206681.952 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-03-21T22:18:01.957Z,1553206681.957 [CTD_Seabird](INFO): created writer for : depth 2019-03-21T22:18:01.958Z,1553206681.958 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-03-21T22:18:01.963Z,1553206681.963 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-03-21T22:18:01.964Z,1553206681.964 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-03-21T22:18:01.969Z,1553206681.969 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-03-21T22:18:01.969Z,1553206681.969 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-03-21T22:18:01.974Z,1553206681.974 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-03-21T22:18:01.975Z,1553206681.975 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-03-21T22:18:01.980Z,1553206681.980 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-03-21T22:18:01.981Z,1553206681.981 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-03-21T22:18:01.985Z,1553206681.985 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-03-21T22:18:02.013Z,1553206682.013 [CTD_Seabird] Loaded 2019-03-21T22:18:02.013Z,1553206682.013 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-03-21T22:18:02.014Z,1553206682.014 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E34E0 2019-03-21T22:18:02.014Z,1553206682.014 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1897 2019-03-21T22:18:02.063Z,1553206682.063 [WetLabsBB2FL] Loaded 2019-03-21T22:18:02.064Z,1553206682.064 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-03-21T22:18:02.065Z,1553206682.065 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A134E0 2019-03-21T22:18:02.065Z,1553206682.065 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1898 2019-03-21T22:18:02.066Z,1553206682.066 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-03-21T22:18:02.066Z,1553206682.066 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-03-21T22:18:02.333Z,1553206682.333 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-03-21T22:18:02.333Z,1553206682.333 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-03-21T22:18:02.465Z,1553206682.465 [SBIT](DEBUG): Construct Startup Built In Test. 2019-03-21T22:18:02.476Z,1553206682.476 [SBIT] Loaded 2019-03-21T22:18:02.476Z,1553206682.476 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-03-21T22:18:02.477Z,1553206682.477 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-03-21T22:18:02.489Z,1553206682.489 [IBIT] Loaded 2019-03-21T22:18:02.489Z,1553206682.489 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-03-21T22:18:02.492Z,1553206682.492 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-03-21T22:18:02.631Z,1553206682.631 [CBIT] Loaded 2019-03-21T22:18:02.632Z,1553206682.632 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-03-21T22:18:02.632Z,1553206682.632 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-03-21T22:18:02.636Z,1553206682.636 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-03-21T22:18:02.636Z,1553206682.636 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-03-21T22:18:02.643Z,1553206682.643 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-03-21T22:18:02.644Z,1553206682.644 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0 2019-03-21T22:18:02.644Z,1553206682.644 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1899 2019-03-21T22:18:02.649Z,1553206682.649 [Supervisor](INFO): Main Thread ID is 1631 2019-03-21T22:18:02.649Z,1553206682.649 [Supervisor](DEBUG): Running supervisor. 2019-03-21T22:18:02.650Z,1553206682.650 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1900 2019-03-21T22:18:02.652Z,1553206682.652 [controlThread ThreadHandler](INFO): Handler Thread ID is 1901 2019-03-21T22:18:02.653Z,1553206682.653 [controlThread](DEBUG): Initializing ControlThread 2019-03-21T22:18:02.654Z,1553206682.654 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-03-21T22:18:02.655Z,1553206682.655 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-03-21T22:18:02.656Z,1553206682.656 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-03-21T22:18:02.657Z,1553206682.657 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-03-21T22:18:02.658Z,1553206682.658 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-03-21T22:18:02.658Z,1553206682.658 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-03-21T22:18:02.658Z,1553206682.658 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-03-21T22:18:02.658Z,1553206682.658 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-03-21T22:18:02.659Z,1553206682.659 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-03-21T22:18:02.659Z,1553206682.659 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-03-21T22:18:02.660Z,1553206682.660 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-21T22:18:02.661Z,1553206682.661 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-03-21T22:18:02.661Z,1553206682.661 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-03-21T22:18:02.668Z,1553206682.668 [SBIT](INFO): Initialize SBIT Component. 2019-03-21T22:18:02.668Z,1553206682.668 [SBIT](IMPORTANT): git: 2019-03-18 2019-03-21T22:18:02.668Z,1553206682.668 [SBIT](INFO): git hash: b35e6a84236bf49b44d0e718aca0c354e2893e2d 2019-03-21T22:18:02.669Z,1553206682.669 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-03-21T22:18:02.670Z,1553206682.670 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 2019-03-21T22:18:02.671Z,1553206682.671 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-03-21T22:18:02.672Z,1553206682.672 [IBIT](INFO): Initialize IBIT Component. 2019-03-21T22:18:02.672Z,1553206682.672 [CBIT](DEBUG): Initialize CBIT Component. 2019-03-21T22:18:02.673Z,1553206682.673 [logger ThreadHandler](INFO): Handler Thread ID is 1902 2019-03-21T22:18:02.686Z,1553206682.686 [CBIT](DEBUG): Initialized mux pins. 2019-03-21T22:18:02.686Z,1553206682.686 [CBIT](DEBUG): Initializing the watchdog timer. 2019-03-21T22:18:02.694Z,1553206682.694 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1903 2019-03-21T22:18:02.695Z,1553206682.695 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-03-21T22:18:02.706Z,1553206682.706 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1904 2019-03-21T22:18:02.710Z,1553206682.710 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-03-21T22:18:02.710Z,1553206682.710 [CBIT](DEBUG): Initializing heartbeat. 2019-03-21T22:18:02.718Z,1553206682.718 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1905 2019-03-21T22:18:02.730Z,1553206682.730 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1906 2019-03-21T22:18:02.730Z,1553206682.730 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-21T22:18:02.734Z,1553206682.734 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-21T22:18:02.735Z,1553206682.735 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1908 2019-03-21T22:18:02.736Z,1553206682.736 [WetLabsBB2FL](INFO): Powering down 2019-03-21T22:18:02.766Z,1553206682.766 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1909 2019-03-21T22:18:02.769Z,1553206682.769 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-21T22:18:02.769Z,1553206682.769 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-03-21T22:18:02.769Z,1553206682.769 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-21T22:18:02.769Z,1553206682.769 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-03-21T22:18:02.770Z,1553206682.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-21T22:18:02.770Z,1553206682.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-03-21T22:18:02.770Z,1553206682.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-21T22:18:02.770Z,1553206682.770 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-03-21T22:18:02.770Z,1553206682.770 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-21T22:18:02.771Z,1553206682.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-03-21T22:18:02.771Z,1553206682.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-21T22:18:02.771Z,1553206682.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-03-21T22:18:02.771Z,1553206682.771 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-21T22:18:02.771Z,1553206682.771 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-03-21T22:18:02.772Z,1553206682.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-21T22:18:02.772Z,1553206682.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-03-21T22:18:02.782Z,1553206682.782 [CBIT](DEBUG): Deactivating GF circuits. 2019-03-21T22:18:02.782Z,1553206682.782 [CBIT](DEBUG): Deactivating emergency mode. 2019-03-21T22:18:02.818Z,1553206682.818 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-03-21T22:18:02.828Z,1553206682.828 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-21T22:18:02.839Z,1553206682.839 [MissionManager](DEBUG): 2019-03-21T22:18:02.840Z,1553206682.840 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-03-21T22:18:02.917Z,1553206682.917 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-03-21T22:18:02.919Z,1553206682.919 [Default:A.Wait](DEBUG): Construct Wait. 2019-03-21T22:18:02.920Z,1553206682.920 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-21T22:18:02.951Z,1553206682.951 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-03-21T22:18:02.954Z,1553206682.954 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-03-21T22:18:02.975Z,1553206682.975 [Default:E.Execute](DEBUG): Construct Execute. 2019-03-21T22:18:02.979Z,1553206682.979 [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 2019-03-21T22:18:02.995Z,1553206682.995 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,ElevatorServo,MassServo,RudderServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-21T22:18:03.090Z,1553206683.090 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-21T22:18:03.094Z,1553206683.094 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-21T22:18:03.095Z,1553206683.095 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-21T22:18:03.106Z,1553206683.106 [MassServo](DEBUG): Initializing MassServo. 2019-03-21T22:18:03.107Z,1553206683.107 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-21T22:18:03.110Z,1553206683.110 [Radio_Surface](INFO): Powering up 2019-03-21T22:18:03.114Z,1553206683.114 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-21T22:18:03.419Z,1553206683.419 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-03-21T22:18:03.419Z,1553206683.419 [DropWeight] Hardware Fault, FailCount= 1 2019-03-21T22:18:03.419Z,1553206683.419 [DropWeight](ERROR): Hardware Fault 2019-03-21T22:18:03.455Z,1553206683.455 [CommandLine](FAULT): Scheduling is paused 2019-03-21T22:18:03.455Z,1553206683.455 [CBIT](INFO): Critical error at 20190321T221803 2019-03-21T22:18:03.455Z,1553206683.455 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-03-21T22:18:03.458Z,1553206683.458 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-03-21T22:18:03.459Z,1553206683.459 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-03-21T22:18:03.887Z,1553206683.887 [CBIT](INFO): Critical error at 20190321T221803 2019-03-21T22:18:07.922Z,1553206687.922 [RudderServo](FAULT): Overload Error 2019-03-21T22:18:07.922Z,1553206687.922 [RudderServo] Hardware Fault, FailCount= 1 2019-03-21T22:18:07.922Z,1553206687.922 [RudderServo](ERROR): Hardware Fault 2019-03-21T22:18:07.962Z,1553206687.962 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-21T22:18:08.306Z,1553206688.306 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-21T22:18:08.307Z,1553206688.307 [RudderServo](INFO): Powering down 2019-03-21T22:18:09.126Z,1553206689.126 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-21T22:18:09.174Z,1553206689.174 [Radio_Surface](INFO): Powering down 2019-03-21T22:18:09.247Z,1553206689.247 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-21T22:18:09.251Z,1553206689.251 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-21T22:18:09.251Z,1553206689.251 [RudderServo] No Fault, FailCount= 1 2019-03-21T22:18:12.750Z,1553206692.750 [CBIT](CRITICAL): Environmental Failure. Press:14.923779 PSI. Humidity:37%. Temp:25 C. ABORTING MISSION 2019-03-21T22:18:13.151Z,1553206693.151 [CBIT](INFO): Critical error at 20190321T221812 2019-03-21T22:18:13.958Z,1553206693.958 [RudderServo](FAULT): Overload Error 2019-03-21T22:18:13.958Z,1553206693.958 [RudderServo] Hardware Fault, FailCount= 2 2019-03-21T22:18:13.958Z,1553206693.958 [RudderServo](ERROR): Hardware Fault 2019-03-21T22:18:13.997Z,1553206693.997 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-21T22:18:14.341Z,1553206694.341 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-21T22:18:14.341Z,1553206694.341 [RudderServo](INFO): Powering down 2019-03-21T22:18:15.145Z,1553206695.145 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-21T22:18:15.266Z,1553206695.266 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-21T22:18:15.270Z,1553206695.270 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-21T22:18:15.270Z,1553206695.270 [RudderServo] No Fault, FailCount= 2 2019-03-21T22:18:15.782Z,1553206695.782 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-03-21T22:18:15.782Z,1553206695.782 [CTD_Seabird] Communications Fault, FailCount= 1 2019-03-21T22:18:15.782Z,1553206695.782 [CTD_Seabird](ERROR): Communications Fault 2019-03-21T22:18:15.997Z,1553206695.997 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-03-21T22:18:16.186Z,1553206696.186 [CTD_Seabird](INFO): Powering down 2019-03-21T22:18:17.173Z,1553206697.173 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-03-21T22:18:17.173Z,1553206697.173 [CTD_Seabird] No Fault, FailCount= 1 2019-03-21T22:18:17.547Z,1553206697.547 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-21T22:18:17.556Z,1553206697.556 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-21T22:18:20.026Z,1553206700.026 [RudderServo](FAULT): Overload Error 2019-03-21T22:18:20.026Z,1553206700.026 [RudderServo] Hardware Fault, FailCount= 3 2019-03-21T22:18:20.026Z,1553206700.026 [RudderServo](ERROR): Hardware Fault 2019-03-21T22:18:20.065Z,1553206700.065 [CBIT](ERROR): Hardware Fault in component: RudderServo 2019-03-21T22:18:20.066Z,1553206700.066 [CBIT](CRITICAL): Hardware Fault in component: RudderServo 2019-03-21T22:18:20.441Z,1553206700.441 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-21T22:18:20.441Z,1553206700.441 [RudderServo](INFO): Powering down 2019-03-21T22:18:20.459Z,1553206700.459 [CBIT](INFO): Critical error at 20190321T221820 2019-03-21T22:18:21.210Z,1553206701.210 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-21T22:18:21.330Z,1553206701.330 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-21T22:18:28.471Z,1553206708.471 [CommandLine](IMPORTANT): got command maintain servo Depth_Keller.depth 0.000000 meter 2019-03-21T22:18:28.472Z,1553206708.472 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2019-03-21T22:18:28.492Z,1553206708.492 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,ElevatorServo,MassServo,RudderServo,Maintain_Depth_Keller.depth,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-21T22:18:29.263Z,1553206709.263 [NAL9602](INFO): Powering up NAL9602 2019-03-21T22:18:29.374Z,1553206709.374 [Radio_Surface](INFO): Powering up 2019-03-21T22:18:30.598Z,1553206710.598 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-03-21T22:18:30.598Z,1553206710.598 [CTD_Seabird] Communications Fault, FailCount= 2 2019-03-21T22:18:30.598Z,1553206710.598 [CTD_Seabird](ERROR): Communications Fault 2019-03-21T22:18:30.948Z,1553206710.948 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-03-21T22:18:31.002Z,1553206711.002 [CTD_Seabird](INFO): Powering down 2019-03-21T22:18:31.310Z,1553206711.310 [SBIT](IMPORTANT): Beginning Startup BIT 2019-03-21T22:18:31.314Z,1553206711.314 [CBIT](IMPORTANT): Beginning ground fault scan 2019-03-21T22:18:32.154Z,1553206712.154 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-03-21T22:18:32.154Z,1553206712.154 [CTD_Seabird] No Fault, FailCount= 2 2019-03-21T22:18:32.346Z,1553206712.346 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-21T22:18:32.354Z,1553206712.354 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-21T22:18:33.402Z,1553206713.402 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:33.806Z,1553206713.806 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:34.210Z,1553206714.210 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:34.614Z,1553206714.614 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:35.422Z,1553206715.422 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:35.466Z,1553206715.466 [Radio_Surface](INFO): Powering down 2019-03-21T22:18:35.826Z,1553206715.826 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:36.235Z,1553206716.235 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:36.638Z,1553206716.638 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:37.042Z,1553206717.042 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:37.446Z,1553206717.446 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:37.850Z,1553206717.850 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:18:40.203Z,1553206720.203 [NAL9602](INFO): NAL9602 initialized 2019-03-21T22:18:41.022Z,1553206721.022 [NAL9602](DEBUG): Fix Requested 2019-03-21T22:18:41.526Z,1553206721.526 [Radio_Surface](INFO): Powering up 2019-03-21T22:18:42.299Z,1553206722.299 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.007492 CHAN A1 (24V): 0.001157 CHAN A2 (12V): -0.068141 CHAN A3 (5V): -0.003243 CHAN B0 (3.3V): -0.000231 CHAN B1 (3.15aV): -0.000817 CHAN B2 (3.15bV): -0.001438 CHAN B3 (GND): -0.000622 OPEN: -0.001094 Full Scale Calc: 4.765 mA, -1.589 mA 2019-03-21T22:18:44.399Z,1553206724.399 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-21T22:18:45.398Z,1553206725.398 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-03-21T22:18:45.398Z,1553206725.398 [CTD_Seabird] Communications Fault, FailCount= 3 2019-03-21T22:18:45.398Z,1553206725.398 [CTD_Seabird](ERROR): Communications Fault 2019-03-21T22:18:45.491Z,1553206725.491 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-03-21T22:18:45.491Z,1553206725.491 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-03-21T22:18:45.802Z,1553206725.802 [CTD_Seabird](INFO): Powering down 2019-03-21T22:18:47.594Z,1553206727.594 [Radio_Surface](INFO): Powering down 2019-03-21T22:19:03.354Z,1553206743.354 [Radio_Surface](INFO): Powering up 2019-03-21T22:19:08.563Z,1553206748.563 [DataOverHttps](ERROR): Failed to read radio surface power status. Assuming ON. 2019-03-21T22:19:11.012Z,1553206751.012 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-21T22:19:11.012Z,1553206751.012 [SBIT](FAULT): Control surface position failure. 2019-03-21T22:19:15.007Z,1553206755.007 [CommandLine](IMPORTANT): got command get platform_mass_position 2019-03-21T22:19:15.008Z,1553206755.008 [CommandLine](IMPORTANT): platform_mass_position -0.001291 m 2019-03-21T22:19:16.328Z,1553206756.328 [BPC1](ERROR): BPC1B: No match for serial number 0202 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-03-21T22:19:17.135Z,1553206757.135 [BPC1](ERROR): BPC1A: No match for serial number 0295 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-03-21T22:19:24.432Z,1553206764.432 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-03-21T22:19:24.432Z,1553206764.432 [SBIT](FAULT): Control surface position failure. 2019-03-21T22:19:24.834Z,1553206764.834 [SBIT](CRITICAL): SBIT FAILED 2019-03-21T22:19:24.849Z,1553206764.849 [CBIT](INFO): Critical error at 20190321T221924 2019-03-21T22:19:24.883Z,1553206764.883 [CommandLine](IMPORTANT): got command configSet list 2019-03-21T22:19:24.884Z,1553206764.884 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-03-21T22:19:24.884Z,1553206764.884 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2019-03-21T22:19:24.885Z,1553206764.885 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2019-03-21T22:19:24.885Z,1553206764.885 [CommandLine](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool; 2019-03-21T22:19:24.885Z,1553206764.885 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=0 bool; 2019-03-21T22:19:24.885Z,1553206764.885 [CommandLine](IMPORTANT): ThrusterServo.loadAtStartup=0 bool; 2019-03-21T22:19:25.238Z,1553206765.238 [MissionManager](IMPORTANT): Started mission Startup 2019-03-21T22:19:25.238Z,1553206765.238 [Startup] Running Loop=1 2019-03-21T22:19:25.238Z,1553206765.238 [Startup](DEBUG): Aggregate::initialize Startup 2019-03-21T22:19:25.238Z,1553206765.238 [Startup:A.GoToSurface] Running Loop=1 2019-03-21T22:19:25.238Z,1553206765.238 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-21T22:19:25.239Z,1553206765.239 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-21T22:19:25.239Z,1553206765.239 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-21T22:19:25.240Z,1553206765.240 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-21T22:19:25.240Z,1553206765.240 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-21T22:19:25.240Z,1553206765.240 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-21T22:19:29.693Z,1553206769.693 [BPC1](ERROR): BPC1B: No match for serial number 0202 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-03-21T22:19:30.500Z,1553206770.500 [BPC1](ERROR): BPC1A: No match for serial number 0295 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-03-21T22:19:40.102Z,1553206780.102 [CommandLine](IMPORTANT): got command get VerticalControl.massDefault 2019-03-21T22:19:40.103Z,1553206780.103 [CommandLine](IMPORTANT): VerticalControl.massDefault 0.200000 cm 2019-03-21T22:19:43.419Z,1553206783.419 [BPC1](ERROR): BPC1B: No match for serial number 0202 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-03-21T22:19:44.226Z,1553206784.226 [BPC1](ERROR): BPC1A: No match for serial number 0295 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-03-21T22:19:57.217Z,1553206797.217 [BPC1](ERROR): BPC1B: No match for serial number 0202 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2019-03-21T22:19:57.551Z,1553206797.551 [BPC1](ERROR): BPC1A: No match for serial number 0295 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-03-21T22:19:57.565Z,1553206797.565 [BPC1](FAULT): Failed to parse data from all battery packs. 2019-03-21T22:19:57.566Z,1553206797.566 [BPC1] Data Fault, FailCount= 1 2019-03-21T22:19:57.566Z,1553206797.566 [BPC1](ERROR): Data Fault 2019-03-21T22:19:57.607Z,1553206797.607 [CBIT](ERROR): Data Fault in component: BPC1 2019-03-21T22:20:02.812Z,1553206802.812 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-21T22:20:02.812Z,1553206802.812 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-21T22:20:02.812Z,1553206802.812 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-21T22:20:02.814Z,1553206802.814 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-21T22:20:02.814Z,1553206802.814 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-21T22:20:02.814Z,1553206802.814 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-21T22:20:02.825Z,1553206802.825 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-21T22:20:02.825Z,1553206802.825 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-21T22:20:03.227Z,1553206803.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-21T22:20:03.227Z,1553206803.227 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-21T22:20:03.227Z,1553206803.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-21T22:20:03.227Z,1553206803.227 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-21T22:20:03.616Z,1553206803.616 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-21T22:20:13.158Z,1553206813.158 [CommandLine](IMPORTANT): got command get depth 2019-03-21T22:20:13.159Z,1553206813.159 [CommandLine](IMPORTANT): depth 0.000000 m 2019-03-21T22:20:43.104Z,1553206843.104 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 0.000000 millimeter 2019-03-21T22:20:43.105Z,1553206843.105 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2019-03-21T22:20:43.246Z,1553206843.246 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,ElevatorServo,MassServo,RudderServo,Maintain_Depth_Keller.depth,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-21T22:21:15.579Z,1553206875.579 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-03-21T22:21:15.579Z,1553206875.579 [CTD_Seabird] No Fault, FailCount= 3 2019-03-21T22:21:15.830Z,1553206875.830 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-21T22:21:15.831Z,1553206875.831 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-21T22:21:28.886Z,1553206888.886 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-03-21T22:21:28.886Z,1553206888.886 [CTD_Seabird] Communications Fault, FailCount= 1 2019-03-21T22:21:28.886Z,1553206888.886 [CTD_Seabird](ERROR): Communications Fault 2019-03-21T22:21:28.901Z,1553206888.901 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-03-21T22:21:29.290Z,1553206889.290 [CTD_Seabird](INFO): Powering down 2019-03-21T22:21:30.523Z,1553206890.523 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-03-21T22:21:30.523Z,1553206890.523 [CTD_Seabird] No Fault, FailCount= 1 2019-03-21T22:21:30.634Z,1553206890.634 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-21T22:21:30.635Z,1553206890.635 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-21T22:21:38.731Z,1553206898.731 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004758 2019-03-21T22:21:43.686Z,1553206903.686 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-03-21T22:21:43.686Z,1553206903.686 [CTD_Seabird] Communications Fault, FailCount= 2 2019-03-21T22:21:43.686Z,1553206903.686 [CTD_Seabird](ERROR): Communications Fault 2019-03-21T22:21:43.843Z,1553206903.843 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-03-21T22:21:44.090Z,1553206904.090 [CTD_Seabird](INFO): Powering down 2019-03-21T22:21:45.054Z,1553206905.054 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2019-03-21T22:21:45.054Z,1553206905.054 [CTD_Seabird] No Fault, FailCount= 2 2019-03-21T22:21:45.435Z,1553206905.435 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-03-21T22:21:45.436Z,1553206905.436 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-03-21T22:21:58.486Z,1553206918.486 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-03-21T22:21:58.486Z,1553206918.486 [CTD_Seabird] Communications Fault, FailCount= 3 2019-03-21T22:21:58.486Z,1553206918.486 [CTD_Seabird](ERROR): Communications Fault 2019-03-21T22:21:58.800Z,1553206918.800 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2019-03-21T22:21:58.800Z,1553206918.800 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2019-03-21T22:21:58.890Z,1553206918.890 [CTD_Seabird](INFO): Powering down 2019-03-21T22:22:03.628Z,1553206923.628 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-21T22:22:03.628Z,1553206923.628 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-21T22:22:03.628Z,1553206923.628 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-21T22:22:03.630Z,1553206923.630 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-21T22:22:03.630Z,1553206923.630 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-21T22:22:03.630Z,1553206923.630 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-21T22:22:03.674Z,1553206923.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-21T22:22:03.674Z,1553206923.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-21T22:22:04.051Z,1553206924.051 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-21T22:22:04.051Z,1553206924.051 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-21T22:22:04.052Z,1553206924.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-21T22:22:04.052Z,1553206924.052 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-21T22:22:04.436Z,1553206924.436 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-21T22:22:23.364Z,1553206943.364 [CommandLine](IMPORTANT): got command restart system 2019-03-21T22:22:25.682Z,1553206945.682 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:25.682Z,1553206945.682 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:25.737Z,1553206945.737 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-03-21T22:22:25.738Z,1553206945.738 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:25.738Z,1553206945.738 [CommandLine](INFO): Join timeout helper Thread ID is 1946 2019-03-21T22:22:25.739Z,1553206945.739 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-03-21T22:22:25.739Z,1553206945.739 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:25.739Z,1553206945.739 [NavChartDb](INFO): Join timeout helper Thread ID is 1947 2019-03-21T22:22:25.974Z,1553206945.974 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:25.974Z,1553206945.974 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:25.989Z,1553206945.989 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-03-21T22:22:25.990Z,1553206945.990 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:25.990Z,1553206945.990 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1948 2019-03-21T22:22:26.390Z,1553206946.390 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:26.390Z,1553206946.390 [WetLabsBB2FL](INFO): Powering down 2019-03-21T22:22:26.391Z,1553206946.391 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.394Z,1553206946.394 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-03-21T22:22:26.394Z,1553206946.394 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.394Z,1553206946.394 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1949 2019-03-21T22:22:26.497Z,1553206946.497 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:26.498Z,1553206946.498 [CTD_Seabird](INFO): Powering down 2019-03-21T22:22:26.509Z,1553206946.509 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.513Z,1553206946.513 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-03-21T22:22:26.514Z,1553206946.514 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.514Z,1553206946.514 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1950 2019-03-21T22:22:26.666Z,1553206946.666 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:26.666Z,1553206946.666 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.675Z,1553206946.675 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-03-21T22:22:26.675Z,1553206946.675 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.675Z,1553206946.675 [Radio_Surface](INFO): Join timeout helper Thread ID is 1951 2019-03-21T22:22:26.970Z,1553206946.970 [Radio_Surface](INFO): Powering down 2019-03-21T22:22:26.971Z,1553206946.971 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:26.971Z,1553206946.971 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.974Z,1553206946.974 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-03-21T22:22:26.974Z,1553206946.974 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:26.975Z,1553206946.975 [DataOverHttps](INFO): Join timeout helper Thread ID is 1952 2019-03-21T22:22:27.186Z,1553206947.186 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:27.186Z,1553206947.186 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.198Z,1553206947.198 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-03-21T22:22:27.198Z,1553206947.198 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.199Z,1553206947.199 [logger](INFO): Join timeout helper Thread ID is 1953 2019-03-21T22:22:27.278Z,1553206947.278 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:27.278Z,1553206947.278 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.298Z,1553206947.298 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-03-21T22:22:27.298Z,1553206947.298 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.299Z,1553206947.299 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-03-21T22:22:27.299Z,1553206947.299 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.299Z,1553206947.299 [controlThread](INFO): Join timeout helper Thread ID is 1954 2019-03-21T22:22:27.434Z,1553206947.434 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-21T22:22:27.434Z,1553206947.434 [controlThread](DEBUG): Uninitializing ControlThread 2019-03-21T22:22:27.434Z,1553206947.434 [NAL9602](INFO): Powering down 2019-03-21T22:22:27.436Z,1553206947.436 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-03-21T22:22:27.437Z,1553206947.437 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-03-21T22:22:27.437Z,1553206947.437 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-03-21T22:22:27.438Z,1553206947.438 [MissionManager](INFO): Uninitializing Mission Default 2019-03-21T22:22:27.440Z,1553206947.440 [MissionManager](INFO): Uninitializing Mission Startup 2019-03-21T22:22:27.440Z,1553206947.440 [Startup] Stopped 2019-03-21T22:22:27.440Z,1553206947.440 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-03-21T22:22:27.441Z,1553206947.441 [Startup:A.GoToSurface] Stopped 2019-03-21T22:22:27.441Z,1553206947.441 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-21T22:22:27.442Z,1553206947.442 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-03-21T22:22:27.442Z,1553206947.442 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-03-21T22:22:27.442Z,1553206947.442 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-03-21T22:22:27.443Z,1553206947.443 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-03-21T22:22:27.443Z,1553206947.443 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-21T22:22:27.443Z,1553206947.443 [ElevatorServo](INFO): Powering down 2019-03-21T22:22:27.444Z,1553206947.444 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-21T22:22:27.444Z,1553206947.444 [MassServo](INFO): Powering down 2019-03-21T22:22:27.445Z,1553206947.445 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-21T22:22:27.445Z,1553206947.445 [RudderServo](INFO): Powering down 2019-03-21T22:22:27.446Z,1553206947.446 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-03-21T22:22:27.446Z,1553206947.446 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-03-21T22:22:27.447Z,1553206947.447 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-03-21T22:22:27.447Z,1553206947.447 [CBIT](DEBUG): Powering off loads. 2019-03-21T22:22:27.457Z,1553206947.457 [CBIT](DEBUG): Disabling WDT. 2019-03-21T22:22:27.469Z,1553206947.469 [CBIT](DEBUG): Opening all GF detection circuits. 2019-03-21T22:22:27.470Z,1553206947.470 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.691Z,1553206947.691 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.697Z,1553206947.697 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.738Z,1553206947.738 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.740Z,1553206947.740 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.744Z,1553206947.744 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.783Z,1553206947.783 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-21T22:22:27.842Z,1553206947.842 [logger ThreadHandler](INFO): Thread cancelled.