2018-10-19T18:00:28.847Z,1539972028.847 [Supervisor](DEBUG): Initializing supervisor. 2018-10-19T18:00:28.849Z,1539972028.849 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-10-19T18:00:28.850Z,1539972028.850 [SyncHandler](INFO): Protected caller Thread ID is 799 2018-10-19T18:00:28.850Z,1539972028.850 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-10-19T18:00:28.851Z,1539972028.851 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-10-19T18:00:28.852Z,1539972028.852 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800 2018-10-19T18:00:28.854Z,1539972028.854 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-10-19T18:00:28.866Z,1539972028.866 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-10-19T18:00:28.867Z,1539972028.867 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-10-19T18:00:28.867Z,1539972028.867 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801 2018-10-19T18:00:28.868Z,1539972028.868 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-10-19T18:00:28.869Z,1539972028.869 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-10-19T18:00:28.869Z,1539972028.869 [logger ThreadHandler](INFO): Protected caller Thread ID is 802 2018-10-19T18:00:28.871Z,1539972028.871 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-10-19T18:00:28.872Z,1539972028.872 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-10-19T18:00:28.876Z,1539972028.876 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-10-19T18:00:29.315Z,1539972029.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-10-19T18:00:29.317Z,1539972029.317 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-10-19T18:00:29.766Z,1539972029.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-10-19T18:00:29.768Z,1539972029.768 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-10-19T18:00:29.963Z,1539972029.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-10-19T18:00:29.964Z,1539972029.964 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-10-19T18:00:30.111Z,1539972030.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-10-19T18:00:30.112Z,1539972030.112 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-10-19T18:00:30.552Z,1539972030.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-10-19T18:00:30.553Z,1539972030.553 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-10-19T18:00:30.650Z,1539972030.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-10-19T18:00:30.650Z,1539972030.650 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-10-19T18:00:30.793Z,1539972030.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-10-19T18:00:30.794Z,1539972030.794 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-10-19T18:00:30.897Z,1539972030.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-10-19T18:00:30.899Z,1539972030.899 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-10-19T18:00:31.003Z,1539972031.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-10-19T18:00:31.004Z,1539972031.004 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-10-19T18:00:31.351Z,1539972031.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-10-19T18:00:31.352Z,1539972031.352 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-10-19T18:00:31.558Z,1539972031.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-10-19T18:00:31.559Z,1539972031.559 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-10-19T18:00:31.774Z,1539972031.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-10-19T18:00:31.774Z,1539972031.774 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-10-19T18:00:31.857Z,1539972031.857 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-10-19T18:00:32.213Z,1539972032.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-10-19T18:00:32.214Z,1539972032.214 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-10-19T18:00:32.850Z,1539972032.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-10-19T18:00:32.852Z,1539972032.852 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2018-10-19T18:00:32.856Z,1539972032.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2018-10-19T18:00:32.941Z,1539972032.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2018-10-19T18:00:33.087Z,1539972033.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2018-10-19T18:00:33.187Z,1539972033.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2018-10-19T18:00:33.361Z,1539972033.361 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2018-10-19T18:00:33.444Z,1539972033.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2018-10-19T18:00:33.670Z,1539972033.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-10-19T18:00:33.672Z,1539972033.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2018-10-19T18:00:33.788Z,1539972033.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2018-10-19T18:00:33.891Z,1539972033.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2018-10-19T18:00:33.987Z,1539972033.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2018-10-19T18:00:34.166Z,1539972034.166 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2018-10-19T18:00:34.291Z,1539972034.291 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-10-19T18:00:34.295Z,1539972034.295 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-10-19T18:00:34.353Z,1539972034.353 [DepthRateCalculator] Loaded 2018-10-19T18:00:34.353Z,1539972034.353 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-10-19T18:00:34.359Z,1539972034.359 [PitchRateCalculator] Loaded 2018-10-19T18:00:34.360Z,1539972034.360 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-10-19T18:00:34.375Z,1539972034.375 [SpeedCalculator] Loaded 2018-10-19T18:00:34.376Z,1539972034.376 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-10-19T18:00:34.396Z,1539972034.396 [TempGradientCalculator] Loaded 2018-10-19T18:00:34.397Z,1539972034.397 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-10-19T18:00:34.403Z,1539972034.403 [YawRateCalculator] Loaded 2018-10-19T18:00:34.403Z,1539972034.403 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-10-19T18:00:34.443Z,1539972034.443 [ElevatorOffsetCalculator] Loaded 2018-10-19T18:00:34.443Z,1539972034.443 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-10-19T18:00:34.443Z,1539972034.443 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-10-19T18:00:34.444Z,1539972034.444 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-10-19T18:00:34.730Z,1539972034.730 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-10-19T18:00:34.732Z,1539972034.732 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-10-19T18:00:35.372Z,1539972035.372 [AHRS_M2] Loaded 2018-10-19T18:00:35.372Z,1539972035.372 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-10-19T18:00:35.509Z,1539972035.509 [DataOverHttps] Loaded 2018-10-19T18:00:35.510Z,1539972035.510 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-10-19T18:00:35.523Z,1539972035.523 [Depth_Keller] Loaded 2018-10-19T18:00:35.523Z,1539972035.523 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-10-19T18:00:35.528Z,1539972035.528 [DropWeight] Loaded 2018-10-19T18:00:35.528Z,1539972035.528 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-10-19T18:00:35.658Z,1539972035.658 [DVL_micro] Loaded 2018-10-19T18:00:35.658Z,1539972035.658 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2018-10-19T18:00:35.757Z,1539972035.757 [NAL9602] Loaded 2018-10-19T18:00:35.757Z,1539972035.757 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-10-19T18:00:35.773Z,1539972035.773 [Onboard] Loaded 2018-10-19T18:00:35.773Z,1539972035.773 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-10-19T18:00:35.784Z,1539972035.784 [Radio_Surface] Loaded 2018-10-19T18:00:35.784Z,1539972035.784 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-10-19T18:00:35.785Z,1539972035.785 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0 2018-10-19T18:00:35.786Z,1539972035.786 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882 2018-10-19T18:00:35.792Z,1539972035.792 [SCPI] Loaded 2018-10-19T18:00:35.793Z,1539972035.793 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-10-19T18:00:35.838Z,1539972035.838 [PNI_TCM] Loaded 2018-10-19T18:00:35.838Z,1539972035.838 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-10-19T18:00:37.603Z,1539972037.603 [BPC1] Loaded 2018-10-19T18:00:37.603Z,1539972037.603 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-10-19T18:00:37.604Z,1539972037.604 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-10-19T18:00:37.604Z,1539972037.604 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-10-19T18:00:37.885Z,1539972037.885 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-10-19T18:00:37.885Z,1539972037.885 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-10-19T18:00:38.032Z,1539972038.032 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-10-19T18:00:38.033Z,1539972038.033 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-10-19T18:00:38.087Z,1539972038.087 [DeadReckonUsingSpeedCalculator] Loaded 2018-10-19T18:00:38.087Z,1539972038.087 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-10-19T18:00:38.103Z,1539972038.103 [NavChart] Loaded 2018-10-19T18:00:38.104Z,1539972038.104 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-10-19T18:00:38.108Z,1539972038.108 [UniversalFixResidualReporter] Loaded 2018-10-19T18:00:38.108Z,1539972038.108 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-10-19T18:00:38.108Z,1539972038.108 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-10-19T18:00:38.109Z,1539972038.109 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-10-19T18:00:38.386Z,1539972038.386 [CTD_NeilBrown] Loaded 2018-10-19T18:00:38.386Z,1539972038.386 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2018-10-19T18:00:38.387Z,1539972038.387 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408CE4E0 2018-10-19T18:00:38.387Z,1539972038.387 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 883 2018-10-19T18:00:38.443Z,1539972038.443 [CTD_Seabird] Loaded 2018-10-19T18:00:38.444Z,1539972038.444 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-10-19T18:00:38.445Z,1539972038.445 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0 2018-10-19T18:00:38.445Z,1539972038.445 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 884 2018-10-19T18:00:38.495Z,1539972038.495 [WetLabsBB2FL] Loaded 2018-10-19T18:00:38.495Z,1539972038.495 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-10-19T18:00:38.496Z,1539972038.496 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0 2018-10-19T18:00:38.497Z,1539972038.497 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 885 2018-10-19T18:00:38.497Z,1539972038.497 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-10-19T18:00:38.498Z,1539972038.498 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-10-19T18:00:38.587Z,1539972038.587 [VerticalControl](DEBUG): Construct VerticalControl. 2018-10-19T18:00:38.686Z,1539972038.686 [VerticalControl] Loaded 2018-10-19T18:00:38.687Z,1539972038.687 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-10-19T18:00:38.688Z,1539972038.688 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-10-19T18:00:38.757Z,1539972038.757 [HorizontalControl] Loaded 2018-10-19T18:00:38.757Z,1539972038.757 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-10-19T18:00:38.758Z,1539972038.758 [SpeedControl](DEBUG): Construct SpeedControl. 2018-10-19T18:00:38.763Z,1539972038.763 [SpeedControl] Loaded 2018-10-19T18:00:38.764Z,1539972038.764 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-10-19T18:00:38.764Z,1539972038.764 [LoopControl](DEBUG): Construct LoopControl. 2018-10-19T18:00:38.765Z,1539972038.765 [LoopControl] Loaded 2018-10-19T18:00:38.765Z,1539972038.765 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-10-19T18:00:38.766Z,1539972038.766 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-10-19T18:00:38.766Z,1539972038.766 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-10-19T18:00:38.790Z,1539972038.790 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-10-19T18:00:38.791Z,1539972038.791 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-10-19T18:00:38.948Z,1539972038.948 [SBIT](DEBUG): Construct Startup Built In Test. 2018-10-19T18:00:38.959Z,1539972038.959 [SBIT] Loaded 2018-10-19T18:00:38.959Z,1539972038.959 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-10-19T18:00:38.960Z,1539972038.960 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-10-19T18:00:38.972Z,1539972038.972 [IBIT] Loaded 2018-10-19T18:00:38.972Z,1539972038.972 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-10-19T18:00:38.975Z,1539972038.975 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-10-19T18:00:39.126Z,1539972039.126 [CBIT] Loaded 2018-10-19T18:00:39.127Z,1539972039.127 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-10-19T18:00:39.127Z,1539972039.127 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-10-19T18:00:39.128Z,1539972039.128 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-10-19T18:00:39.256Z,1539972039.256 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-10-19T18:00:39.257Z,1539972039.257 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-10-19T18:00:39.294Z,1539972039.294 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-10-19T18:00:39.295Z,1539972039.295 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-10-19T18:00:39.417Z,1539972039.417 [BuoyancyServo] Loaded 2018-10-19T18:00:39.418Z,1539972039.418 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-10-19T18:00:39.429Z,1539972039.429 [ElevatorServo] Loaded 2018-10-19T18:00:39.430Z,1539972039.430 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-10-19T18:00:39.440Z,1539972039.440 [MassServo] Loaded 2018-10-19T18:00:39.441Z,1539972039.441 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-10-19T18:00:39.452Z,1539972039.452 [RudderServo] Loaded 2018-10-19T18:00:39.452Z,1539972039.452 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-10-19T18:00:39.463Z,1539972039.463 [ThrusterServo] Loaded 2018-10-19T18:00:39.463Z,1539972039.463 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-10-19T18:00:39.464Z,1539972039.464 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-10-19T18:00:39.467Z,1539972039.467 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-10-19T18:00:39.468Z,1539972039.468 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-10-19T18:00:39.475Z,1539972039.475 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-10-19T18:00:39.476Z,1539972039.476 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2018-10-19T18:00:39.476Z,1539972039.476 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886 2018-10-19T18:00:39.481Z,1539972039.481 [Supervisor](INFO): Main Thread ID is 795 2018-10-19T18:00:39.481Z,1539972039.481 [Supervisor](DEBUG): Running supervisor. 2018-10-19T18:00:39.482Z,1539972039.482 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887 2018-10-19T18:00:39.484Z,1539972039.484 [controlThread ThreadHandler](INFO): Handler Thread ID is 888 2018-10-19T18:00:39.484Z,1539972039.484 [controlThread](DEBUG): Initializing ControlThread 2018-10-19T18:00:39.485Z,1539972039.485 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-10-19T18:00:39.486Z,1539972039.486 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-10-19T18:00:39.486Z,1539972039.486 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-10-19T18:00:39.486Z,1539972039.486 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-10-19T18:00:39.487Z,1539972039.487 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-10-19T18:00:39.487Z,1539972039.487 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-10-19T18:00:39.495Z,1539972039.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-10-19T18:00:39.496Z,1539972039.496 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-10-19T18:00:39.497Z,1539972039.497 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-10-19T18:00:39.497Z,1539972039.497 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-19T18:00:39.498Z,1539972039.498 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-19T18:00:39.498Z,1539972039.498 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-19T18:00:39.499Z,1539972039.499 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-10-19T18:00:39.499Z,1539972039.499 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-10-19T18:00:39.499Z,1539972039.499 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-10-19T18:00:39.501Z,1539972039.501 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-10-19T18:00:39.502Z,1539972039.502 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-10-19T18:00:39.502Z,1539972039.502 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-10-19T18:00:39.503Z,1539972039.503 [SBIT](INFO): Initialize SBIT Component. 2018-10-19T18:00:39.503Z,1539972039.503 [SBIT](IMPORTANT): git: 2018-10-09-31-g8f1ca26 2018-10-19T18:00:39.503Z,1539972039.503 [SBIT](INFO): git hash: 8f1ca2616133f591d84f59109522710795260801 2018-10-19T18:00:39.504Z,1539972039.504 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-10-19T18:00:39.504Z,1539972039.504 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-10-19T18:00:39.505Z,1539972039.505 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2018-10-19T18:00:39.506Z,1539972039.506 [IBIT](INFO): Initialize IBIT Component. 2018-10-19T18:00:39.507Z,1539972039.507 [CBIT](DEBUG): Initialize CBIT Component. 2018-10-19T18:00:39.507Z,1539972039.507 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-10-19T18:00:39.507Z,1539972039.507 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-10-19T18:00:39.508Z,1539972039.508 [logger ThreadHandler](INFO): Handler Thread ID is 889 2018-10-19T18:00:39.525Z,1539972039.525 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 890 2018-10-19T18:00:39.531Z,1539972039.531 [Radio_Surface](INFO): Powering up 2018-10-19T18:00:39.538Z,1539972039.538 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 891 2018-10-19T18:00:39.549Z,1539972039.549 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 892 2018-10-19T18:00:39.550Z,1539972039.550 [CTD_Seabird](INFO): Initializing 2018-10-19T18:00:39.550Z,1539972039.550 [CTD_Seabird](INFO): Checking LCM 2018-10-19T18:00:39.615Z,1539972039.615 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-10-19T18:00:39.666Z,1539972039.666 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 893 2018-10-19T18:00:39.668Z,1539972039.668 [WetLabsBB2FL](INFO): Powering down 2018-10-19T18:00:39.685Z,1539972039.685 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 894 2018-10-19T18:00:39.829Z,1539972039.829 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-19T18:00:39.835Z,1539972039.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-10-19T18:00:39.835Z,1539972039.835 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-10-19T18:00:39.835Z,1539972039.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-10-19T18:00:39.836Z,1539972039.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-10-19T18:00:39.836Z,1539972039.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-10-19T18:00:39.836Z,1539972039.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-10-19T18:00:39.836Z,1539972039.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-10-19T18:00:39.836Z,1539972039.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-10-19T18:00:39.836Z,1539972039.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-10-19T18:00:39.837Z,1539972039.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-10-19T18:00:39.837Z,1539972039.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-10-19T18:00:39.837Z,1539972039.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-10-19T18:00:39.837Z,1539972039.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-10-19T18:00:39.837Z,1539972039.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-10-19T18:00:39.838Z,1539972039.838 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-10-19T18:00:39.838Z,1539972039.838 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-10-19T18:00:39.872Z,1539972039.872 [MissionManager](DEBUG): 2018-10-19T18:00:39.901Z,1539972039.901 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-10-19T18:00:40.100Z,1539972040.100 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-10-19T18:00:40.101Z,1539972040.101 [Default:A.Wait](DEBUG): Construct Wait. 2018-10-19T18:00:40.103Z,1539972040.103 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-10-19T18:00:40.113Z,1539972040.113 [CTD_Seabird](INFO): LCM OK 2018-10-19T18:00:40.113Z,1539972040.113 [CTD_Seabird](INFO): Powering up 2018-10-19T18:00:40.127Z,1539972040.127 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-10-19T18:00:40.165Z,1539972040.165 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-10-19T18:00:40.171Z,1539972040.171 [Default:E.Execute](DEBUG): Construct Execute. 2018-10-19T18:00:40.188Z,1539972040.188 [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 2018-10-19T18:00:40.194Z,1539972040.194 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,SCPI,PNI_TCM,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-10-19T18:00:40.216Z,1539972040.216 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-10-19T18:00:40.345Z,1539972040.345 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-10-19T18:00:40.359Z,1539972040.359 [Depth_Keller](ERROR): Pressure reading out of range: 1827.005127 decibar 2018-10-19T18:00:40.395Z,1539972040.395 [DVL_micro](INFO): Initializing 2018-10-19T18:00:40.428Z,1539972040.428 [Onboard](ERROR): I2C read error. Can't read 1 bytes. 2018-10-19T18:00:40.428Z,1539972040.428 [Onboard](FAULT): Can't read temperature LSB. 2018-10-19T18:00:40.428Z,1539972040.428 [Onboard] Hardware Fault, FailCount= 1 2018-10-19T18:00:40.428Z,1539972040.428 [Onboard](ERROR): Hardware Fault 2018-10-19T18:00:40.429Z,1539972040.429 [Onboard](ERROR): I2C read error. Can't read 1 bytes. 2018-10-19T18:00:40.429Z,1539972040.429 [Onboard](FAULT): Can't read temperature MSB. 2018-10-19T18:00:40.430Z,1539972040.430 [Onboard](ERROR): I2C read error. Can't read 1 bytes. 2018-10-19T18:00:40.430Z,1539972040.430 [Onboard](FAULT): Can't read pressure LSB. 2018-10-19T18:00:40.430Z,1539972040.430 [Onboard](ERROR): I2C read error. Can't read 1 bytes. 2018-10-19T18:00:40.431Z,1539972040.431 [Onboard](FAULT): Can't read pressure MSB. 2018-10-19T18:00:40.501Z,1539972040.501 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-10-19T18:00:40.781Z,1539972040.781 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-19T18:00:40.789Z,1539972040.789 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-19T18:00:40.811Z,1539972040.811 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-10-19T18:00:40.817Z,1539972040.817 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-10-19T18:00:40.831Z,1539972040.831 [MassServo](DEBUG): Initializing EZServoServo. 2018-10-19T18:00:40.837Z,1539972040.837 [MassServo](DEBUG): Initializing MassServo. 2018-10-19T18:00:40.851Z,1539972040.851 [RudderServo](DEBUG): Initializing EZServoServo. 2018-10-19T18:00:40.857Z,1539972040.857 [RudderServo](DEBUG): Initializing RudderServo. 2018-10-19T18:00:40.878Z,1539972040.878 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-10-19T18:00:40.885Z,1539972040.885 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-10-19T18:00:40.933Z,1539972040.933 [CBIT](ERROR): Hardware Fault in component: Onboard 2018-10-19T18:00:40.933Z,1539972040.933 [CBIT](CRITICAL): Hardware Fault in component: Onboard 2018-10-19T18:00:41.020Z,1539972041.020 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-10-19T18:00:41.021Z,1539972041.021 [DropWeight] Hardware Fault, FailCount= 1 2018-10-19T18:00:41.021Z,1539972041.021 [DropWeight](ERROR): Hardware Fault 2018-10-19T18:00:41.065Z,1539972041.065 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-10-19T18:00:41.105Z,1539972041.105 [CommandLine](FAULT): Scheduling is paused 2018-10-19T18:00:41.106Z,1539972041.106 [CBIT](INFO): Critical error at 20181019T180041 2018-10-19T18:00:41.106Z,1539972041.106 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-10-19T18:00:41.108Z,1539972041.108 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-10-19T18:00:41.109Z,1539972041.109 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-10-19T18:00:41.717Z,1539972041.717 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-19T18:00:41.717Z,1539972041.717 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-19T18:00:41.717Z,1539972041.717 [BuoyancyServo] Communications Fault, FailCount= 1 2018-10-19T18:00:41.717Z,1539972041.717 [BuoyancyServo](ERROR): Communications Fault 2018-10-19T18:00:41.923Z,1539972041.923 [CBIT](INFO): Critical error at 20181019T180041 2018-10-19T18:00:41.925Z,1539972041.925 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-19T18:00:41.991Z,1539972041.991 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-19T18:00:41.991Z,1539972041.991 [BuoyancyServo](INFO): Powering down 2018-10-19T18:00:43.857Z,1539972043.857 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:44.369Z,1539972044.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:44.425Z,1539972044.425 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-19T18:00:44.425Z,1539972044.425 [BuoyancyServo] No Fault, FailCount= 1 2018-10-19T18:00:44.765Z,1539972044.765 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:44.827Z,1539972044.827 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-19T18:00:44.877Z,1539972044.877 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-19T18:00:44.878Z,1539972044.878 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-19T18:00:44.968Z,1539972044.968 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-10-19T18:00:45.100Z,1539972045.100 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd 2018-10-19T18:00:45.187Z,1539972045.187 [Radio_Surface](INFO): Powering down 2018-10-19T18:00:45.341Z,1539972045.341 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:45.507Z,1539972045.507 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-10-19T18:00:45.507Z,1539972045.507 [CTD_Seabird](INFO): Powering down 2018-10-19T18:00:45.721Z,1539972045.721 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-10-19T18:00:45.721Z,1539972045.721 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-10-19T18:00:45.721Z,1539972045.721 [BuoyancyServo] Communications Fault, FailCount= 2 2018-10-19T18:00:45.721Z,1539972045.721 [BuoyancyServo](ERROR): Communications Fault 2018-10-19T18:00:46.038Z,1539972046.038 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-10-19T18:00:46.369Z,1539972046.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:46.429Z,1539972046.429 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-10-19T18:00:46.437Z,1539972046.437 [BuoyancyServo](INFO): Powering down 2018-10-19T18:00:46.817Z,1539972046.817 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:47.237Z,1539972047.237 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:47.249Z,1539972047.249 [PNI_TCM](FAULT): Failed to acquire valid data within timeout. 2018-10-19T18:00:47.249Z,1539972047.249 [PNI_TCM] Data Fault, FailCount= 1 2018-10-19T18:00:47.249Z,1539972047.249 [PNI_TCM](ERROR): Data Fault 2018-10-19T18:00:47.310Z,1539972047.310 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-10-19T18:00:47.310Z,1539972047.310 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 2018-10-19T18:00:47.641Z,1539972047.641 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:47.688Z,1539972047.688 [CBIT](INFO): Critical error at 20181019T180047 2018-10-19T18:00:48.041Z,1539972048.041 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:48.445Z,1539972048.445 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:48.514Z,1539972048.514 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-10-19T18:00:48.514Z,1539972048.514 [BuoyancyServo] No Fault, FailCount= 2 2018-10-19T18:00:48.837Z,1539972048.837 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:48.871Z,1539972048.871 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-10-19T18:00:48.990Z,1539972048.990 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-10-19T18:00:49.353Z,1539972049.353 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:49.753Z,1539972049.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:49.902Z,1539972049.902 [CBIT](CRITICAL): Environmental Failure. Press:0.000000 PSI. Humidity:2139112%. Temp:4991584 C. ABORTING MISSION 2018-10-19T18:00:50.233Z,1539972050.233 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:50.284Z,1539972050.284 [CBIT](INFO): Critical error at 20181019T180049 2018-10-19T18:00:50.637Z,1539972050.637 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:50.637Z,1539972050.637 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-10-19T18:00:50.637Z,1539972050.637 [AHRS_M2] Communications Fault, FailCount= 1 2018-10-19T18:00:50.637Z,1539972050.637 [AHRS_M2](ERROR): Communications Fault 2018-10-19T18:00:50.693Z,1539972050.693 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-10-19T18:00:50.713Z,1539972050.713 [AHRS_M2](INFO): Powering down 2018-10-19T18:00:54.361Z,1539972054.361 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-10-19T18:00:54.361Z,1539972054.361 [AHRS_M2] No Fault, FailCount= 1 2018-10-19T18:00:54.713Z,1539972054.713 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-10-19T18:00:58.237Z,1539972058.237 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:58.637Z,1539972058.637 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:59.065Z,1539972059.065 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:59.477Z,1539972059.477 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:00:59.853Z,1539972059.853 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:00.289Z,1539972060.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:00.701Z,1539972060.701 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:01.097Z,1539972061.097 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:01.493Z,1539972061.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:01.897Z,1539972061.897 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:02.305Z,1539972062.305 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:02.713Z,1539972062.713 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:03.141Z,1539972063.141 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:03.541Z,1539972063.541 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:03.941Z,1539972063.941 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:04.369Z,1539972064.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:04.769Z,1539972064.769 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:04.769Z,1539972064.769 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-10-19T18:01:04.769Z,1539972064.769 [AHRS_M2] Communications Fault, FailCount= 2 2018-10-19T18:01:04.769Z,1539972064.769 [AHRS_M2](ERROR): Communications Fault 2018-10-19T18:01:04.833Z,1539972064.833 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-10-19T18:01:04.846Z,1539972064.846 [AHRS_M2](INFO): Powering down 2018-10-19T18:01:06.054Z,1539972066.054 [NAL9602](INFO): Powering up NAL9602 2018-10-19T18:01:08.095Z,1539972068.095 [SBIT](IMPORTANT): Beginning Startup BIT 2018-10-19T18:01:08.131Z,1539972068.131 [CBIT](IMPORTANT): Beginning ground fault scan 2018-10-19T18:01:08.571Z,1539972068.571 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-10-19T18:01:08.571Z,1539972068.571 [AHRS_M2] No Fault, FailCount= 2 2018-10-19T18:01:08.845Z,1539972068.845 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-10-19T18:01:12.369Z,1539972072.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:12.893Z,1539972072.893 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:13.413Z,1539972073.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:13.841Z,1539972073.841 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:14.345Z,1539972074.345 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:14.753Z,1539972074.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:15.213Z,1539972075.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:15.625Z,1539972075.625 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:16.173Z,1539972076.173 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:16.593Z,1539972076.593 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:17.021Z,1539972077.021 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:17.027Z,1539972077.027 [NAL9602](INFO): NAL9602 initialized 2018-10-19T18:01:17.569Z,1539972077.569 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:18.017Z,1539972078.017 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:18.445Z,1539972078.445 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:18.977Z,1539972078.977 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:18.977Z,1539972078.977 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-10-19T18:01:18.977Z,1539972078.977 [AHRS_M2] Communications Fault, FailCount= 3 2018-10-19T18:01:18.977Z,1539972078.977 [AHRS_M2](ERROR): Communications Fault 2018-10-19T18:01:19.114Z,1539972079.114 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-10-19T18:01:19.122Z,1539972079.122 [AHRS_M2](INFO): Powering down 2018-10-19T18:01:20.454Z,1539972080.454 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.007910 CHAN A1 (24V): 0.001082 CHAN A2 (12V): -0.005269 CHAN A3 (5V): -0.003117 CHAN B0 (3.3V): -0.001073 CHAN B1 (3.15aV): -0.001368 CHAN B2 (3.15bV): -0.001512 CHAN B3 (GND): -0.000345 OPEN: -0.001260 Full Scale Calc: 4.765 mA, -1.589 mA 2018-10-19T18:01:21.200Z,1539972081.200 [SBIT](FAULT): Mass: EXPECTED:0.009000 ACTUAL:0.004814 2018-10-19T18:01:22.814Z,1539972082.814 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-10-19T18:01:22.814Z,1539972082.814 [AHRS_M2] No Fault, FailCount= 3 2018-10-19T18:01:23.125Z,1539972083.125 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-10-19T18:01:26.649Z,1539972086.649 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:27.141Z,1539972087.141 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:27.609Z,1539972087.609 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:28.069Z,1539972088.069 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:28.537Z,1539972088.537 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:29.133Z,1539972089.133 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:29.673Z,1539972089.673 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:30.093Z,1539972090.093 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:30.529Z,1539972090.529 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:30.949Z,1539972090.949 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:31.373Z,1539972091.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:31.801Z,1539972091.801 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:32.245Z,1539972092.245 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:32.737Z,1539972092.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:33.149Z,1539972093.149 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:33.149Z,1539972093.149 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-10-19T18:01:33.149Z,1539972093.149 [AHRS_M2] Communications Fault, FailCount= 4 2018-10-19T18:01:33.149Z,1539972093.149 [AHRS_M2](ERROR): Communications Fault 2018-10-19T18:01:33.231Z,1539972093.231 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-10-19T18:01:33.246Z,1539972093.246 [AHRS_M2](INFO): Powering down 2018-10-19T18:01:36.893Z,1539972096.893 [CBIT](INFO): Clearing failed state for component AHRS_M2 2018-10-19T18:01:36.893Z,1539972096.893 [AHRS_M2] No Fault, FailCount= 4 2018-10-19T18:01:37.249Z,1539972097.249 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-10-19T18:01:40.773Z,1539972100.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:41.185Z,1539972101.185 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:41.605Z,1539972101.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:42.001Z,1539972102.001 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:42.413Z,1539972102.413 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:42.813Z,1539972102.813 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:43.213Z,1539972103.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:43.605Z,1539972103.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:44.005Z,1539972104.005 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:44.405Z,1539972104.405 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:44.805Z,1539972104.805 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:45.213Z,1539972105.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:45.613Z,1539972105.613 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:46.013Z,1539972106.013 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:46.410Z,1539972106.410 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:46.825Z,1539972106.825 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:47.229Z,1539972107.229 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:47.629Z,1539972107.629 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2018-10-19T18:01:47.629Z,1539972107.629 [AHRS_M2](FAULT): Failed to initialize within timeout. 2018-10-19T18:01:47.629Z,1539972107.629 [AHRS_M2] Communications Fault, FailCount= 5 2018-10-19T18:01:47.629Z,1539972107.629 [AHRS_M2](ERROR): Communications Fault 2018-10-19T18:01:47.729Z,1539972107.729 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2018-10-19T18:01:47.729Z,1539972107.729 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2018-10-19T18:01:47.736Z,1539972107.736 [AHRS_M2](INFO): Powering down 2018-10-19T18:01:47.815Z,1539972107.815 [BPC1](ERROR): BPC1A: No match for serial number 0226 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2018-10-19T18:01:47.816Z,1539972107.816 [BPC1](ERROR): BPC1B: No match for serial number 0273 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2018-10-19T18:01:47.817Z,1539972107.817 [BPC1](FAULT): Failed to parse data from all battery packs. 2018-10-19T18:01:47.817Z,1539972107.817 [BPC1] Data Fault, FailCount= 1 2018-10-19T18:01:47.818Z,1539972107.818 [BPC1](ERROR): Data Fault 2018-10-19T18:01:47.834Z,1539972107.834 [CBIT](INFO): Critical error at 20181019T180147 2018-10-19T18:01:47.836Z,1539972107.836 [CBIT](ERROR): Data Fault in component: BPC1 2018-10-19T18:02:01.459Z,1539972121.459 [SBIT](CRITICAL): SBIT FAILED 2018-10-19T18:02:01.498Z,1539972121.498 [CommandLine](IMPORTANT): got command configSet list 2018-10-19T18:02:01.499Z,1539972121.499 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-10-19T18:02:01.499Z,1539972121.499 [CommandLine](IMPORTANT): No configSet variables persisted 2018-10-19T18:02:01.505Z,1539972121.505 [CBIT](INFO): Critical error at 20181019T180201 2018-10-19T18:02:01.859Z,1539972121.859 [MissionManager](IMPORTANT): Started mission Startup 2018-10-19T18:02:01.859Z,1539972121.859 [Startup] Running Loop=1 2018-10-19T18:02:01.859Z,1539972121.859 [Startup](DEBUG): Aggregate::initialize Startup 2018-10-19T18:02:01.859Z,1539972121.859 [Startup:A.GoToSurface] Running Loop=1 2018-10-19T18:02:01.859Z,1539972121.859 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-10-19T18:02:01.860Z,1539972121.860 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-10-19T18:02:01.860Z,1539972121.860 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-10-19T18:02:01.861Z,1539972121.861 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-10-19T18:02:01.861Z,1539972121.861 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-10-19T18:02:01.862Z,1539972121.862 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-10-19T18:02:01.998Z,1539972121.998 [Radio_Surface](INFO): Powering up 2018-10-19T18:02:39.877Z,1539972159.877 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-10-19T18:02:39.877Z,1539972159.877 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2018-10-19T18:02:39.877Z,1539972159.877 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-10-19T18:02:39.879Z,1539972159.879 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-10-19T18:02:39.879Z,1539972159.879 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2018-10-19T18:02:39.879Z,1539972159.879 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-10-19T18:02:39.925Z,1539972159.925 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-10-19T18:02:39.926Z,1539972159.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-10-19T18:02:40.277Z,1539972160.277 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-10-19T18:02:40.277Z,1539972160.277 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2018-10-19T18:02:40.278Z,1539972160.278 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-10-19T18:02:40.278Z,1539972160.278 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2018-10-19T18:02:40.655Z,1539972160.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-10-19T18:02:40.655Z,1539972160.655 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-10-19T18:02:40.656Z,1539972160.656 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-10-19T18:02:40.656Z,1539972160.656 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-10-19T18:02:40.656Z,1539972160.656 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-10-19T18:02:40.657Z,1539972160.657 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-10-19T18:02:54.019Z,1539972174.019 [CommandLine](IMPORTANT): got command show stack 2018-10-19T18:02:54.019Z,1539972174.019 [CommandLine](IMPORTANT): Behavior Stack: 2018-10-19T18:02:54.019Z,1539972174.019 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface 2018-10-19T18:03:42.646Z,1539972222.646 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2018-10-19T18:03:42.646Z,1539972222.646 [DVL_micro] Data Fault, FailCount= 1 2018-10-19T18:03:42.646Z,1539972222.646 [DVL_micro](ERROR): Data Fault 2018-10-19T18:03:42.709Z,1539972222.709 [CBIT](ERROR): Data Fault in component: DVL_micro 2018-10-19T18:03:42.709Z,1539972222.709 [CBIT](CRITICAL): Data Fault in component: DVL_micro 2018-10-19T18:03:43.048Z,1539972223.048 [DVL_micro](INFO): uninitialize:Powering down 2018-10-19T18:03:43.084Z,1539972223.084 [CBIT](INFO): Critical error at 20181019T180342 2018-10-19T18:03:58.663Z,1539972238.663 [VerticalControl](CRITICAL): Excessive depth excursion=26.920242 m, failToGoUpDepth_=66.308899 m, depthRate=2.636040 m/s, pitch=nan deg. 2018-10-19T18:03:58.669Z,1539972238.669 [CBIT](INFO): Critical error at 20181019T180358 2018-10-19T18:04:03.864Z,1539972243.864 [VerticalControl](CRITICAL): Dropping drop weight at excessive depth excursion=35.664604, failToGoUpDepth_=66.308899, depthRate=3.028018 m/s, pitch=nan deg. 2018-10-19T18:04:03.898Z,1539972243.898 [CBIT](INFO): Critical error at 20181019T180403 2018-10-19T18:04:40.663Z,1539972280.663 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-10-19T18:04:40.664Z,1539972280.664 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2018-10-19T18:04:40.664Z,1539972280.664 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2018-10-19T18:04:40.666Z,1539972280.666 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2018-10-19T18:04:40.666Z,1539972280.666 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2018-10-19T18:04:40.666Z,1539972280.666 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-10-19T18:04:40.680Z,1539972280.680 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2018-10-19T18:04:40.680Z,1539972280.680 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-10-19T18:04:41.080Z,1539972281.080 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2018-10-19T18:04:41.080Z,1539972281.080 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2018-10-19T18:04:41.080Z,1539972281.080 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-10-19T18:04:41.080Z,1539972281.080 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2018-10-19T18:04:41.452Z,1539972281.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-10-19T18:04:41.465Z,1539972281.465 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-10-19T18:04:41.465Z,1539972281.465 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-10-19T18:04:41.466Z,1539972281.466 [DeadReckonUsingSpeedCalculat