2006-03-29T19:29:50.790Z,1143660590.790 [Supervisor](DEBUG): Initializing supervisor. 2006-03-29T19:29:50.795Z,1143660590.795 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2006-03-29T19:29:50.795Z,1143660590.795 [SyncHandler](INFO): Protected caller Thread ID is 1033 2006-03-29T19:29:50.796Z,1143660590.796 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2006-03-29T19:29:50.797Z,1143660590.797 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2006-03-29T19:29:50.797Z,1143660590.797 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1034 2006-03-29T19:29:50.801Z,1143660590.801 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2006-03-29T19:29:50.821Z,1143660590.821 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2006-03-29T19:29:50.822Z,1143660590.822 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2006-03-29T19:29:50.822Z,1143660590.822 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1035 2006-03-29T19:29:50.826Z,1143660590.826 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2006-03-29T19:29:50.827Z,1143660590.827 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2006-03-29T19:29:50.828Z,1143660590.828 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1036 2006-03-29T19:29:50.830Z,1143660590.830 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2006-03-29T19:29:50.831Z,1143660590.831 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2006-03-29T19:29:50.831Z,1143660590.831 [logger ThreadHandler](INFO): Protected caller Thread ID is 1037 2006-03-29T19:29:50.835Z,1143660590.835 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2006-03-29T19:29:50.835Z,1143660590.835 [Supervisor](INFO): Looking for Config files in directory: Config/ 2006-03-29T19:29:50.837Z,1143660590.837 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2006-03-29T19:29:51.180Z,1143660591.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2006-03-29T19:29:51.181Z,1143660591.181 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2006-03-29T19:29:51.975Z,1143660591.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2006-03-29T19:29:51.975Z,1143660591.975 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2006-03-29T19:29:52.091Z,1143660592.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2006-03-29T19:29:52.092Z,1143660592.092 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2006-03-29T19:29:52.610Z,1143660592.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2006-03-29T19:29:52.611Z,1143660592.611 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2006-03-29T19:29:52.714Z,1143660592.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2006-03-29T19:29:52.714Z,1143660592.714 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2006-03-29T19:29:52.812Z,1143660592.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2006-03-29T19:29:52.813Z,1143660592.813 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2006-03-29T19:29:53.217Z,1143660593.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2006-03-29T19:29:53.217Z,1143660593.217 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2006-03-29T19:29:53.439Z,1143660593.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2006-03-29T19:29:53.440Z,1143660593.440 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2006-03-29T19:29:53.584Z,1143660593.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2006-03-29T19:29:53.584Z,1143660593.584 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2006-03-29T19:29:53.832Z,1143660593.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2006-03-29T19:29:53.833Z,1143660593.833 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2006-03-29T19:29:54.374Z,1143660594.374 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2006-03-29T19:29:54.374Z,1143660594.374 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2006-03-29T19:29:54.763Z,1143660594.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2006-03-29T19:29:54.764Z,1143660594.764 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2006-03-29T19:29:54.992Z,1143660594.992 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2006-03-29T19:29:55.356Z,1143660595.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2006-03-29T19:29:55.357Z,1143660595.357 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2006-03-29T19:29:55.658Z,1143660595.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2006-03-29T19:29:55.659Z,1143660595.659 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2006-03-29T19:29:55.952Z,1143660595.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2006-03-29T19:29:55.955Z,1143660595.955 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2006-03-29T19:29:55.955Z,1143660595.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2006-03-29T19:29:56.060Z,1143660596.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2006-03-29T19:29:56.214Z,1143660596.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2006-03-29T19:29:56.307Z,1143660596.307 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2006-03-29T19:29:56.398Z,1143660596.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2006-03-29T19:29:56.516Z,1143660596.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2006-03-29T19:29:56.616Z,1143660596.616 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Dock.cfg 2006-03-29T19:29:56.733Z,1143660596.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2006-03-29T19:29:56.844Z,1143660596.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2006-03-29T19:29:57.110Z,1143660597.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2006-03-29T19:29:57.111Z,1143660597.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2006-03-29T19:29:57.248Z,1143660597.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2006-03-29T19:29:57.348Z,1143660597.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2006-03-29T19:29:57.450Z,1143660597.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2006-03-29T19:29:57.543Z,1143660597.543 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2006-03-29T19:29:57.543Z,1143660597.543 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2006-03-29T19:29:57.549Z,1143660597.549 [controlThread](FAULT): Changing base units of DAT.transponderAddress from enum to count 2006-03-29T19:29:57.555Z,1143660597.555 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2006-03-29T19:29:57.690Z,1143660597.690 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2006-03-29T19:29:57.691Z,1143660597.691 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2006-03-29T19:29:57.701Z,1143660597.701 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2006-03-29T19:29:57.702Z,1143660597.702 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2006-03-29T19:29:57.754Z,1143660597.754 [DepthRateCalculator] Loaded 2006-03-29T19:29:57.754Z,1143660597.754 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2006-03-29T19:29:57.759Z,1143660597.759 [PitchRateCalculator] Loaded 2006-03-29T19:29:57.760Z,1143660597.760 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2006-03-29T19:29:57.770Z,1143660597.770 [SpeedCalculator] Loaded 2006-03-29T19:29:57.770Z,1143660597.770 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2006-03-29T19:29:57.775Z,1143660597.775 [YawRateCalculator] Loaded 2006-03-29T19:29:57.775Z,1143660597.775 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2006-03-29T19:29:57.796Z,1143660597.796 [ElevatorOffsetCalculator] Loaded 2006-03-29T19:29:57.797Z,1143660597.797 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2006-03-29T19:29:57.797Z,1143660597.797 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2006-03-29T19:29:57.798Z,1143660597.798 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2006-03-29T19:29:57.851Z,1143660597.851 [VerticalControl](DEBUG): Construct VerticalControl. 2006-03-29T19:29:57.906Z,1143660597.906 [VerticalControl] Loaded 2006-03-29T19:29:57.907Z,1143660597.907 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2006-03-29T19:29:57.909Z,1143660597.909 [HorizontalControl](DEBUG): Construct HorizontalControl. 2006-03-29T19:29:57.955Z,1143660597.955 [HorizontalControl] Loaded 2006-03-29T19:29:57.955Z,1143660597.955 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2006-03-29T19:29:57.958Z,1143660597.958 [SpeedControl](DEBUG): Construct SpeedControl. 2006-03-29T19:29:57.960Z,1143660597.960 [SpeedControl] Loaded 2006-03-29T19:29:57.960Z,1143660597.960 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2006-03-29T19:29:57.963Z,1143660597.963 [LoopControl](DEBUG): Construct LoopControl. 2006-03-29T19:29:57.964Z,1143660597.964 [LoopControl] Loaded 2006-03-29T19:29:57.964Z,1143660597.964 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2006-03-29T19:29:57.964Z,1143660597.964 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2006-03-29T19:29:57.965Z,1143660597.965 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2006-03-29T19:29:58.077Z,1143660598.077 [DeadReckonUsingMultipleVelocitySources] Loaded 2006-03-29T19:29:58.078Z,1143660598.078 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2006-03-29T19:29:58.091Z,1143660598.091 [NavChart] Loaded 2006-03-29T19:29:58.092Z,1143660598.092 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2006-03-29T19:29:58.097Z,1143660598.097 [UniversalFixResidualReporter] Loaded 2006-03-29T19:29:58.097Z,1143660598.097 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2006-03-29T19:29:58.098Z,1143660598.098 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2006-03-29T19:29:58.098Z,1143660598.098 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2006-03-29T19:29:58.276Z,1143660598.276 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2006-03-29T19:29:58.276Z,1143660598.276 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2006-03-29T19:29:58.787Z,1143660598.787 [AHRS_M2] Loaded 2006-03-29T19:29:58.788Z,1143660598.788 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2006-03-29T19:29:58.826Z,1143660598.826 [BackseatComponent] Loaded 2006-03-29T19:29:58.827Z,1143660598.827 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2006-03-29T19:29:58.828Z,1143660598.828 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409414E0 2006-03-29T19:29:58.828Z,1143660598.828 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1125 2006-03-29T19:29:58.831Z,1143660598.831 [LcmPublisher](INFO): Full slate access is ENABLED. All requested slate values (including non-universals) will be published on LCM. 2006-03-29T19:29:58.831Z,1143660598.831 [LcmPublisher] Loaded 2006-03-29T19:29:58.832Z,1143660598.832 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2006-03-29T19:29:59.638Z,1143660599.638 [BPC1] Loaded 2006-03-29T19:29:59.638Z,1143660599.638 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2006-03-29T19:29:59.735Z,1143660599.735 [DAT] Loaded 2006-03-29T19:29:59.735Z,1143660599.735 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2006-03-29T19:29:59.736Z,1143660599.736 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409714E0 2006-03-29T19:29:59.737Z,1143660599.737 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1126 2006-03-29T19:29:59.808Z,1143660599.808 [DataOverHttps] Loaded 2006-03-29T19:29:59.808Z,1143660599.808 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2006-03-29T19:29:59.809Z,1143660599.809 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0 2006-03-29T19:29:59.809Z,1143660599.809 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1127 2006-03-29T19:29:59.832Z,1143660599.832 [Depth_Keller] Loaded 2006-03-29T19:29:59.832Z,1143660599.832 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2006-03-29T19:29:59.837Z,1143660599.837 [DropWeight] Loaded 2006-03-29T19:29:59.837Z,1143660599.837 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2006-03-29T19:29:59.900Z,1143660599.900 [NAL9602] Loaded 2006-03-29T19:29:59.901Z,1143660599.901 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2006-03-29T19:29:59.930Z,1143660599.930 [Onboard] Loaded 2006-03-29T19:29:59.930Z,1143660599.930 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2006-03-29T19:29:59.931Z,1143660599.931 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D14E0 2006-03-29T19:29:59.931Z,1143660599.931 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1128 2006-03-29T19:29:59.945Z,1143660599.945 [Power24vConverter] Loaded 2006-03-29T19:29:59.946Z,1143660599.946 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2006-03-29T19:29:59.961Z,1143660599.961 [Radio_Surface] Loaded 2006-03-29T19:29:59.961Z,1143660599.961 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2006-03-29T19:29:59.962Z,1143660599.962 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A014E0 2006-03-29T19:29:59.963Z,1143660599.963 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1129 2006-03-29T19:30:00.008Z,1143660600.008 [RDI_Pathfinder] Loaded 2006-03-29T19:30:00.009Z,1143660600.009 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2006-03-29T19:30:00.024Z,1143660600.024 [Sonardyne_Nano] Loaded 2006-03-29T19:30:00.024Z,1143660600.024 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2006-03-29T19:30:00.024Z,1143660600.024 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2006-03-29T19:30:00.025Z,1143660600.025 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2006-03-29T19:30:00.255Z,1143660600.255 [BuoyancyServo] Loaded 2006-03-29T19:30:00.255Z,1143660600.255 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2006-03-29T19:30:00.283Z,1143660600.283 [ElevatorServo] Loaded 2006-03-29T19:30:00.283Z,1143660600.283 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2006-03-29T19:30:00.312Z,1143660600.312 [DockingStepper] Loaded 2006-03-29T19:30:00.313Z,1143660600.313 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2006-03-29T19:30:00.332Z,1143660600.332 [MassServo] Loaded 2006-03-29T19:30:00.332Z,1143660600.332 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2006-03-29T19:30:00.352Z,1143660600.352 [RudderServo] Loaded 2006-03-29T19:30:00.352Z,1143660600.352 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2006-03-29T19:30:00.369Z,1143660600.369 [ThrusterHE] Loaded 2006-03-29T19:30:00.369Z,1143660600.369 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2006-03-29T19:30:00.369Z,1143660600.369 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2006-03-29T19:30:00.370Z,1143660600.370 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2006-03-29T19:30:00.431Z,1143660600.431 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2006-03-29T19:30:00.432Z,1143660600.432 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2006-03-29T19:30:00.462Z,1143660600.462 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2006-03-29T19:30:00.463Z,1143660600.463 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2006-03-29T19:30:00.809Z,1143660600.809 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2006-03-29T19:30:00.810Z,1143660600.810 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2006-03-29T19:30:00.984Z,1143660600.984 [CTD_Seabird] Loaded 2006-03-29T19:30:00.984Z,1143660600.984 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2006-03-29T19:30:00.985Z,1143660600.985 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC34E0 2006-03-29T19:30:00.986Z,1143660600.986 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1130 2006-03-29T19:30:01.021Z,1143660601.021 [ESPComponent] Loaded 2006-03-29T19:30:01.021Z,1143660601.021 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2006-03-29T19:30:01.043Z,1143660601.043 [PAR_Licor] Loaded 2006-03-29T19:30:01.043Z,1143660601.043 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2006-03-29T19:30:01.088Z,1143660601.088 [WetLabsBB2FL] Loaded 2006-03-29T19:30:01.088Z,1143660601.088 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2006-03-29T19:30:01.089Z,1143660601.089 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF34E0 2006-03-29T19:30:01.090Z,1143660601.090 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1131 2006-03-29T19:30:01.090Z,1143660601.090 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2006-03-29T19:30:01.091Z,1143660601.091 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2006-03-29T19:30:01.253Z,1143660601.253 [SBIT](DEBUG): Construct Startup Built In Test. 2006-03-29T19:30:01.262Z,1143660601.262 [SBIT] Loaded 2006-03-29T19:30:01.262Z,1143660601.262 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2006-03-29T19:30:01.265Z,1143660601.265 [IBIT](DEBUG): Construct Initiated Built In Test. 2006-03-29T19:30:01.277Z,1143660601.277 [IBIT] Loaded 2006-03-29T19:30:01.277Z,1143660601.277 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2006-03-29T19:30:01.283Z,1143660601.283 [CBIT](DEBUG): Construct Continuous Built In Test. 2006-03-29T19:30:01.363Z,1143660601.363 [CBIT] Loaded 2006-03-29T19:30:01.363Z,1143660601.363 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2006-03-29T19:30:01.395Z,1143660601.395 [GFScanner] Loaded 2006-03-29T19:30:01.395Z,1143660601.395 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2006-03-29T19:30:01.395Z,1143660601.395 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2006-03-29T19:30:01.401Z,1143660601.401 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2006-03-29T19:30:01.405Z,1143660601.405 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2006-03-29T19:30:01.416Z,1143660601.416 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2006-03-29T19:30:01.417Z,1143660601.417 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6E4E0 2006-03-29T19:30:01.417Z,1143660601.417 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1132 2006-03-29T19:30:01.422Z,1143660601.422 [Supervisor](INFO): Main Thread ID is 826 2006-03-29T19:30:01.422Z,1143660601.422 [Supervisor](DEBUG): Running supervisor. 2006-03-29T19:30:01.422Z,1143660601.422 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1133 2006-03-29T19:30:01.423Z,1143660601.423 [CommandExec](INFO): Initializing the command executive. 2006-03-29T19:30:01.425Z,1143660601.425 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1134 2006-03-29T19:30:01.428Z,1143660601.428 [controlThread ThreadHandler](INFO): Handler Thread ID is 1135 2006-03-29T19:30:01.428Z,1143660601.428 [controlThread](DEBUG): Initializing ControlThread 2006-03-29T19:30:01.429Z,1143660601.429 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2006-03-29T19:30:01.429Z,1143660601.429 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2006-03-29T19:30:01.430Z,1143660601.430 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2006-03-29T19:30:01.430Z,1143660601.430 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2006-03-29T19:30:01.430Z,1143660601.430 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2006-03-29T19:30:01.431Z,1143660601.431 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2006-03-29T19:30:01.433Z,1143660601.433 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2006-03-29T19:30:01.433Z,1143660601.433 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2006-03-29T19:30:01.434Z,1143660601.434 [LoopControl](DEBUG): Initialize LoopControlComponent. 2006-03-29T19:30:01.435Z,1143660601.435 [NavChart](DEBUG): Initialize NavChart Navigation. 2006-03-29T19:30:01.435Z,1143660601.435 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2006-03-29T19:30:01.442Z,1143660601.442 [SBIT](INFO): Initialize SBIT Component. 2006-03-29T19:30:01.442Z,1143660601.442 [SBIT](IMPORTANT): git: 2025-11-06-2-g37d2ccc16 2006-03-29T19:30:01.442Z,1143660601.442 [SBIT](INFO): git hash: 37d2ccc16c3820cae3b4f7d56ca89ff0df066476 2006-03-29T19:30:01.443Z,1143660601.443 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2006-03-29T19:30:01.444Z,1143660601.444 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2006-03-29T19:30:01.445Z,1143660601.445 [SBIT](INFO): Beginning SBIT in 109.000000 seconds. 2006-03-29T19:30:01.445Z,1143660601.445 [IBIT](INFO): Initialize IBIT Component. 2006-03-29T19:30:01.446Z,1143660601.446 [CBIT](DEBUG): Initialize CBIT Component. 2006-03-29T19:30:01.447Z,1143660601.447 [logger ThreadHandler](INFO): Handler Thread ID is 1136 2006-03-29T19:30:01.457Z,1143660601.457 [CBIT](DEBUG): Initialized mux pins. 2006-03-29T19:30:01.458Z,1143660601.458 [CBIT](DEBUG): Initializing the watchdog timer. 2006-03-29T19:30:01.466Z,1143660601.466 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1137 2006-03-29T19:30:01.478Z,1143660601.478 [DAT ThreadHandler](INFO): Handler Thread ID is 1138 2006-03-29T19:30:01.478Z,1143660601.478 [DAT](INFO): Powering up 2006-03-29T19:30:01.479Z,1143660601.479 [DAT](DEBUG): Initializing DAT. 2006-03-29T19:30:01.481Z,1143660601.481 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2006-03-29T19:30:01.482Z,1143660601.482 [CBIT](DEBUG): Initializing heartbeat. 2006-03-29T19:30:01.484Z,1143660601.484 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1140 2006-03-29T19:30:01.485Z,1143660601.485 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2006-03-29T19:30:01.494Z,1143660601.494 [Onboard ThreadHandler](INFO): Handler Thread ID is 1141 2006-03-29T19:30:01.524Z,1143660601.524 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1142 2006-03-29T19:30:01.538Z,1143660601.538 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1143 2006-03-29T19:30:01.538Z,1143660601.538 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2006-03-29T19:30:01.543Z,1143660601.543 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1145 2006-03-29T19:30:01.546Z,1143660601.546 [WetLabsBB2FL](INFO): Powering up 2006-03-29T19:30:01.547Z,1143660601.547 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1147 2006-03-29T19:30:01.551Z,1143660601.551 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2006-03-29T19:30:01.551Z,1143660601.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2006-03-29T19:30:01.552Z,1143660601.552 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2006-03-29T19:30:01.552Z,1143660601.552 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2006-03-29T19:30:01.552Z,1143660601.552 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2006-03-29T19:30:01.552Z,1143660601.552 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2006-03-29T19:30:01.552Z,1143660601.552 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2006-03-29T19:30:01.552Z,1143660601.552 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2006-03-29T19:30:01.553Z,1143660601.553 [CBIT](DEBUG): Deactivating emergency mode. 2006-03-29T19:30:01.593Z,1143660601.593 [CBIT](DEBUG): Backplane powered. 2006-03-29T19:30:01.594Z,1143660601.594 [GFScanner](DEBUG): Initializing GFScanner 2006-03-29T19:30:01.594Z,1143660601.594 [GFScanner](DEBUG): Deactivating GF circuits. 2006-03-29T19:30:01.598Z,1143660601.598 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2006-03-29T19:30:01.599Z,1143660601.599 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2006-03-29T19:30:01.599Z,1143660601.599 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2006-03-29T19:30:01.602Z,1143660601.602 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2006-03-29T19:30:01.608Z,1143660601.608 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2006-03-29T19:30:01.609Z,1143660601.609 [MissionManager](ERROR): Top node has no child nodes: Mission 2006-03-29T19:30:01.609Z,1143660601.609 [MissionManager](ERROR): Could not load startup mission. 2006-03-29T19:30:01.609Z,1143660601.609 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2006-03-29T19:30:01.610Z,1143660601.610 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2006-03-29T19:30:01.611Z,1143660601.611 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2006-03-29T19:30:01.925Z,1143660601.925 [Radio_Surface](INFO): Powering up 2006-03-29T19:30:02.176Z,1143660602.176 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into: 0 6 10 1 3 30 Timed out while trying to detach from dock at current depth of . Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2006-03-29T19:30:02.196Z,1143660602.196 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2006-03-29T19:30:02.219Z,1143660602.219 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2006-03-29T19:30:02.223Z,1143660602.223 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2006-03-29T19:30:02.243Z,1143660602.243 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2006-03-29T19:30:02.247Z,1143660602.247 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2006-03-29T19:30:02.270Z,1143660602.270 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2006-03-29T19:30:02.272Z,1143660602.272 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2006-03-29T19:30:02.418Z,1143660602.418 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2006-03-29T19:30:02.418Z,1143660602.418 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2006-03-29T19:30:02.475Z,1143660602.475 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2006-03-29T19:30:02.521Z,1143660602.521 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2006-03-29T19:30:02.524Z,1143660602.524 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2006-03-29T19:30:02.546Z,1143660602.546 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2006-03-29T19:30:02.567Z,1143660602.567 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission DefaultWithUndock { arguments { ElapsedSinceDefaultStarted = 0 minute DetachTimeout = 6 hour """ Maximum time duration for the vehicle to detach form the dock. """ TransponderCode = Dock:Dock.transponderCode """ Dock transponder address. """ TrackingUpdatePeriod = 10 second """ Time duration between acoustic range queries while the mission is detaching from the dock. """ NumberOfPings = 1 count """ Number of pings requested each time. """ DriftTimeout = 3 minute """ How long to allow vehicle to drift away from dock before retrying reverse """ } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 30 second } aggregate LeaveDock { run in sequence timeout duration=DetachTimeout { syslog important "Timed out while trying to detach from dock at current depth of " + Universal:depth~meter + "." } # Acoustic tracking directives behavior Estimation:TrackAcousticContact { run while ( Universal:depth > Control:VerticalControl.surfaceThreshold ) set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } # Leave the dock behavior Dock:Undock { run in sequence set driftTimeout = DriftTimeout } } aggregate SurfaceDefault { run in sequence # This mission assumes open water so from here on it's the same old default that's always used behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P2H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2006-03-29T19:30:02.572Z,1143660602.572 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter, 2006-03-29T19:30:02.599Z,1143660602.599 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2006-03-29T19:30:02.658Z,1143660602.658 [Depth_Keller](INFO): Initializing. 2006-03-29T19:30:02.659Z,1143660602.659 [Power24vConverter](INFO): Powering up. 2006-03-29T19:30:02.660Z,1143660602.660 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2006-03-29T19:30:02.666Z,1143660602.666 [Sonardyne_Nano](INFO): Initializing. 2006-03-29T19:30:02.694Z,1143660602.694 [DepthRateCalculator](ERROR): Depth measurement is not active 2006-03-29T19:30:02.730Z,1143660602.730 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:30:02.738Z,1143660602.738 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2006-03-29T19:30:02.740Z,1143660602.740 [ElevatorServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:30:02.746Z,1143660602.746 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2006-03-29T19:30:02.747Z,1143660602.747 [DockingStepper](DEBUG): Initializing EZServoServo. 2006-03-29T19:30:02.754Z,1143660602.754 [DockingStepper](DEBUG): Initializing DockingStepper. 2006-03-29T19:30:02.755Z,1143660602.755 [MassServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:30:02.762Z,1143660602.762 [MassServo](DEBUG): Initializing MassServo. 2006-03-29T19:30:02.762Z,1143660602.762 [RudderServo](DEBUG): Initializing EZServoServo. 2006-03-29T19:30:02.770Z,1143660602.770 [RudderServo](DEBUG): Initializing RudderServo. 2006-03-29T19:30:02.771Z,1143660602.771 [ThrusterHE](DEBUG): Initializing EZServoServo. 2006-03-29T19:30:02.778Z,1143660602.778 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2006-03-29T19:30:02.780Z,1143660602.780 [CommandExec](FAULT): Scheduling is paused 2006-03-29T19:30:02.780Z,1143660602.780 [CBIT](INFO): Critical error at 20060329T193001 2006-03-29T19:30:02.780Z,1143660602.780 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2006-03-29T19:30:03.045Z,1143660603.045 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2006-03-29T19:30:03.045Z,1143660603.045 [DropWeight] Hardware Fault, FailCount= 1 2006-03-29T19:30:03.045Z,1143660603.045 [DropWeight](ERROR): Hardware Fault 2006-03-29T19:30:03.084Z,1143660603.084 [CBIT](INFO): Critical error at 20060329T193003 2006-03-29T19:30:03.095Z,1143660603.095 [CBIT](ERROR): Hardware Fault in component: DropWeight 2006-03-29T19:30:03.095Z,1143660603.095 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2006-03-29T19:30:03.408Z,1143660603.408 [CBIT](INFO): Critical error at 20060329T193003 2006-03-29T19:30:03.653Z,1143660603.653 [WetLabsBB2FL](INFO): Powering down 2006-03-29T19:30:04.600Z,1143660604.600 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2006-03-29T19:30:05.899Z,1143660605.899 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2006-03-29T19:30:07.179Z,1143660607.179 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2006-03-29T19:30:07.180Z,1143660607.180 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0 2006-03-29T19:30:08.526Z,1143660608.526 [ThrusterHE](ERROR): Zero Speed Commanded. 2006-03-29T19:30:13.070Z,1143660613.070 [DAT](INFO): DAT read: 2006-03-29T19:30:13.071Z,1143660613.071 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2006-03-29T19:30:14.582Z,1143660614.582 [DAT](INFO): DAT read: MF Frequency Band 2006-03-29T19:30:14.584Z,1143660614.584 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2006-03-29T19:30:14.584Z,1143660614.584 [DAT](INFO): DAT read: Nov 12 2025 19:30:08 2006-03-29T19:30:15.591Z,1143660615.591 [DAT](INFO): DAT read: Features enabled [Bearing] 2006-03-29T19:30:15.592Z,1143660615.592 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2006-03-29T19:30:15.592Z,1143660615.592 [DAT](INFO): commRate: 600 2006-03-29T19:30:17.658Z,1143660617.658 [DAT](INFO): entering command mode 2006-03-29T19:30:17.858Z,1143660617.858 [DAT](INFO): DAT read: 2006-03-29T19:30:17.859Z,1143660617.859 [DAT](INFO): DAT read: user:1> 2006-03-29T19:30:17.859Z,1143660617.859 [DAT](INFO): setting verbose to 3 2006-03-29T19:30:18.110Z,1143660618.110 [DAT](INFO): DAT read: user:1> 2006-03-29T19:30:18.111Z,1143660618.111 [DAT](INFO): DAT read: Verbose | 3 2006-03-29T19:30:18.112Z,1143660618.112 [DAT](INFO): set verbose to 3 2006-03-29T19:30:18.112Z,1143660618.112 [DAT](INFO): setting DatVerbose to 27440 2006-03-29T19:30:18.362Z,1143660618.362 [DAT](INFO): DAT read: user:2> 2006-03-29T19:30:18.363Z,1143660618.363 [DAT](INFO): DAT read: DatVerbose | 27440 2006-03-29T19:30:18.363Z,1143660618.363 [DAT](INFO): set DatVerbose to 27440 2006-03-29T19:30:18.364Z,1143660618.364 [DAT](INFO): setting transmit power to 8 2006-03-29T19:30:18.614Z,1143660618.614 [DAT](INFO): DAT read: user:3> 2006-03-29T19:30:18.615Z,1143660618.615 [DAT](INFO): DAT read: TxPower | 8 (Max) 2006-03-29T19:30:18.616Z,1143660618.616 [DAT](INFO): set transmit power to 8 2006-03-29T19:30:18.616Z,1143660618.616 [DAT](INFO): setting local address to 11 2006-03-29T19:30:18.866Z,1143660618.866 [DAT](INFO): DAT read: user:4> 2006-03-29T19:30:18.867Z,1143660618.867 [DAT](INFO): DAT read: LocalAddr | 11 2006-03-29T19:30:18.867Z,1143660618.867 [DAT](INFO): set local address to 11 2006-03-29T19:30:18.869Z,1143660618.869 [DAT](INFO): Setting time to: 19:30:18 And date to:3/29/2006 2006-03-29T19:30:19.118Z,1143660619.118 [DAT](INFO): DAT read: user:5> 2006-03-29T19:30:19.119Z,1143660619.119 [DAT](INFO): DAT read: Wed Mar 29, 2006 19:30:18 2006-03-29T19:30:19.119Z,1143660619.119 [DAT](INFO): Local DAT time set to Wed Mar 29, 2006 19:30:18 2006-03-29T19:30:30.055Z,1143660630.055 [NAL9602](INFO): Powering up NAL9602 2006-03-29T19:30:32.606Z,1143660632.606 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:30:40.972Z,1143660640.972 [NAL9602](INFO): NAL9602 initialized 2006-03-29T19:31:03.730Z,1143660663.730 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:31:29.104Z,1143660689.104 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2006-03-29T19:31:34.854Z,1143660694.854 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:31:50.873Z,1143660710.873 [SBIT](IMPORTANT): Beginning Startup BIT 2006-03-29T19:31:50.878Z,1143660710.878 [GFScanner](IMPORTANT): Beginning ground fault scan 2006-03-29T19:31:55.554Z,1143660715.554 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.003197 CHAN A1 (24V): -0.000193 CHAN A2 (12V): 0.000223 CHAN A3 (5V): -0.000446 CHAN B0 (3.3V): 0.000887 CHAN B1 (3.15aV): -0.000176 CHAN B2 (3.15bV): 0.001016 CHAN B3 (GND): -0.000121 OPEN: 0.004723 Full Scale: +/- 1 mA 2006-03-29T19:32:05.978Z,1143660725.978 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:32:13.764Z,1143660733.764 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004569 2006-03-29T19:32:14.762Z,1143660734.762 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:32:23.258Z,1143660743.258 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:32:31.718Z,1143660751.718 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:32:40.474Z,1143660760.474 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:32:45.185Z,1143660765.185 [SBIT](IMPORTANT): SBIT PASSED 2006-03-29T19:32:45.186Z,1143660765.186 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2006-03-29T19:32:45.186Z,1143660765.186 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=29 count; 2006-03-29T19:32:45.186Z,1143660765.186 [SBIT](IMPORTANT): BackseatComponent.fullSlateAccess=1 bool; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): DAT.surfaceThreshold=-0.5 meter; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): DAT.transponderAddress=10 count; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-0.1 ampere_hour; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.800000 m/s; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=433.530334 cubic_centimeter; 2006-03-29T19:32:45.187Z,1143660765.187 [SBIT](IMPORTANT): VerticalControl.massDefault=22.433727 millimeter; 2006-03-29T19:32:45.463Z,1143660765.463 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2006-03-29T19:32:45.464Z,1143660765.464 [DefaultWithUndock] Running Loop=1 2006-03-29T19:32:45.464Z,1143660765.464 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2006-03-29T19:32:45.464Z,1143660765.464 [DefaultWithUndock:A.Wait] Running Loop=1 2006-03-29T19:32:45.464Z,1143660765.464 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2006-03-29T19:32:49.162Z,1143660769.162 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:32:57.622Z,1143660777.622 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:33:01.621Z,1143660781.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2006-03-29T19:33:01.621Z,1143660781.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2006-03-29T19:33:01.649Z,1143660781.649 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2006-03-29T19:33:02.050Z,1143660782.050 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2006-03-29T19:33:02.050Z,1143660782.050 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2006-03-29T19:33:06.282Z,1143660786.282 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:33:13.690Z,1143660793.690 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2006-03-29T19:33:15.787Z,1143660795.787 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2006-03-29T19:33:15.787Z,1143660795.787 [DefaultWithUndock:A.Wait] Stopped 2006-03-29T19:33:15.787Z,1143660795.787 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2006-03-29T19:33:15.787Z,1143660795.787 [DefaultWithUndock:LeaveDock] Running Loop=1 2006-03-29T19:33:15.787Z,1143660795.787 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2006-03-29T19:33:15.787Z,1143660795.787 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2006-03-29T19:33:15.787Z,1143660795.787 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2006-03-29T19:33:15.788Z,1143660795.788 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2006-03-29T19:33:15.788Z,1143660795.788 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2006-03-29T19:33:15.788Z,1143660795.788 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2006-03-29T19:33:16.278Z,114366