2019-03-07T22:42:35.932Z,1551998555.932 [Supervisor](DEBUG): Initializing supervisor. 2019-03-07T22:42:35.935Z,1551998555.935 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-03-07T22:42:35.935Z,1551998555.935 [SyncHandler](INFO): Protected caller Thread ID is 7445 2019-03-07T22:42:35.936Z,1551998555.936 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-03-07T22:42:35.937Z,1551998555.937 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-03-07T22:42:35.937Z,1551998555.937 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7446 2019-03-07T22:42:35.940Z,1551998555.940 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-03-07T22:42:35.951Z,1551998555.951 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-03-07T22:42:35.952Z,1551998555.952 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-03-07T22:42:35.953Z,1551998555.953 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7447 2019-03-07T22:42:35.954Z,1551998555.954 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-03-07T22:42:35.954Z,1551998555.954 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-03-07T22:42:35.955Z,1551998555.955 [logger ThreadHandler](INFO): Protected caller Thread ID is 7448 2019-03-07T22:42:35.957Z,1551998555.957 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-03-07T22:42:35.957Z,1551998555.957 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-03-07T22:42:35.959Z,1551998555.959 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-03-07T22:42:36.395Z,1551998556.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-03-07T22:42:36.396Z,1551998556.396 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-03-07T22:42:36.494Z,1551998556.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-03-07T22:42:36.495Z,1551998556.495 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-03-07T22:42:36.830Z,1551998556.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-03-07T22:42:36.831Z,1551998556.831 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-03-07T22:42:36.975Z,1551998556.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-03-07T22:42:36.976Z,1551998556.976 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-03-07T22:42:37.173Z,1551998557.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-03-07T22:42:37.174Z,1551998557.174 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-03-07T22:42:37.647Z,1551998557.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-03-07T22:42:37.647Z,1551998557.647 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-03-07T22:42:37.863Z,1551998557.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-03-07T22:42:37.864Z,1551998557.864 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-03-07T22:42:38.013Z,1551998558.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-03-07T22:42:38.014Z,1551998558.014 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-03-07T22:42:38.284Z,1551998558.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-03-07T22:42:38.285Z,1551998558.285 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-03-07T22:42:38.383Z,1551998558.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-03-07T22:42:38.384Z,1551998558.384 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-03-07T22:42:38.702Z,1551998558.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-03-07T22:42:38.703Z,1551998558.703 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-03-07T22:42:38.782Z,1551998558.782 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-03-07T22:42:38.885Z,1551998558.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-03-07T22:42:38.886Z,1551998558.886 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-03-07T22:42:39.396Z,1551998559.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-03-07T22:42:39.397Z,1551998559.397 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-03-07T22:42:39.801Z,1551998559.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-03-07T22:42:39.803Z,1551998559.803 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2019-03-07T22:42:39.804Z,1551998559.804 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2019-03-07T22:42:40.017Z,1551998560.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2019-03-07T22:42:40.118Z,1551998560.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2019-03-07T22:42:40.216Z,1551998560.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2019-03-07T22:42:40.447Z,1551998560.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-03-07T22:42:40.448Z,1551998560.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2019-03-07T22:42:40.532Z,1551998560.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2019-03-07T22:42:40.625Z,1551998560.625 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2019-03-07T22:42:40.767Z,1551998560.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2019-03-07T22:42:40.897Z,1551998560.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2019-03-07T22:42:41.064Z,1551998561.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2019-03-07T22:42:41.388Z,1551998561.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2019-03-07T22:42:41.774Z,1551998561.774 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2019-03-07T22:42:41.775Z,1551998561.775 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-03-07T22:42:41.779Z,1551998561.779 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-03-07T22:42:42.108Z,1551998562.108 [DataOverHttps] Loaded 2019-03-07T22:42:42.108Z,1551998562.108 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-03-07T22:42:42.109Z,1551998562.109 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0 2019-03-07T22:42:42.110Z,1551998562.110 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7528 2019-03-07T22:42:42.124Z,1551998562.124 [Depth_Keller] Loaded 2019-03-07T22:42:42.124Z,1551998562.124 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-03-07T22:42:42.129Z,1551998562.129 [DropWeight] Loaded 2019-03-07T22:42:42.130Z,1551998562.130 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-03-07T22:42:42.178Z,1551998562.178 [DUSBL_Hydroid] Loaded 2019-03-07T22:42:42.179Z,1551998562.179 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-03-07T22:42:42.220Z,1551998562.220 [Micromodem] Loaded 2019-03-07T22:42:42.220Z,1551998562.220 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2019-03-07T22:42:42.319Z,1551998562.319 [NAL9602] Loaded 2019-03-07T22:42:42.319Z,1551998562.319 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-03-07T22:42:42.335Z,1551998562.335 [Onboard] Loaded 2019-03-07T22:42:42.335Z,1551998562.335 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-03-07T22:42:42.342Z,1551998562.342 [Radio_Surface] Loaded 2019-03-07T22:42:42.342Z,1551998562.342 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-03-07T22:42:42.343Z,1551998562.343 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0 2019-03-07T22:42:42.343Z,1551998562.343 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7529 2019-03-07T22:42:42.388Z,1551998562.388 [RDI_Pathfinder] Loaded 2019-03-07T22:42:42.389Z,1551998562.389 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-03-07T22:42:42.411Z,1551998562.411 [RDI_PathfinderUp] Loaded 2019-03-07T22:42:42.411Z,1551998562.411 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2019-03-07T22:42:44.228Z,1551998564.228 [BPC1] Loaded 2019-03-07T22:42:44.228Z,1551998564.228 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-03-07T22:42:44.229Z,1551998564.229 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-03-07T22:42:44.229Z,1551998564.229 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-03-07T22:42:44.243Z,1551998564.243 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-03-07T22:42:44.243Z,1551998564.243 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-03-07T22:42:44.351Z,1551998564.351 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-03-07T22:42:44.351Z,1551998564.351 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-03-07T22:42:44.411Z,1551998564.411 [DeadReckonUsingSpeedCalculator] Loaded 2019-03-07T22:42:44.411Z,1551998564.411 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-03-07T22:42:44.471Z,1551998564.471 [DeadReckonWithRespectToSeafloor] Loaded 2019-03-07T22:42:44.471Z,1551998564.471 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-03-07T22:42:44.492Z,1551998564.492 [NavChart] Loaded 2019-03-07T22:42:44.492Z,1551998564.492 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-03-07T22:42:44.496Z,1551998564.496 [UniversalFixResidualReporter] Loaded 2019-03-07T22:42:44.496Z,1551998564.496 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-03-07T22:42:44.497Z,1551998564.497 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-03-07T22:42:44.497Z,1551998564.497 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-03-07T22:42:44.602Z,1551998564.602 [BuoyancyServo] Loaded 2019-03-07T22:42:44.602Z,1551998564.602 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-03-07T22:42:44.617Z,1551998564.617 [ElevatorServo] Loaded 2019-03-07T22:42:44.617Z,1551998564.617 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-03-07T22:42:44.632Z,1551998564.632 [MassServo] Loaded 2019-03-07T22:42:44.632Z,1551998564.632 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-03-07T22:42:44.648Z,1551998564.648 [RudderServo] Loaded 2019-03-07T22:42:44.648Z,1551998564.648 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-03-07T22:42:44.663Z,1551998564.663 [ThrusterServo] Loaded 2019-03-07T22:42:44.663Z,1551998564.663 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-03-07T22:42:44.663Z,1551998564.663 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-03-07T22:42:44.664Z,1551998564.664 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-03-07T22:42:44.849Z,1551998564.849 [Aanderaa_O2] Loaded 2019-03-07T22:42:44.849Z,1551998564.849 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2019-03-07T22:42:44.936Z,1551998564.936 [CTD_NeilBrown] Loaded 2019-03-07T22:42:44.936Z,1551998564.936 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-03-07T22:42:44.937Z,1551998564.937 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0 2019-03-07T22:42:44.938Z,1551998564.938 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7530 2019-03-07T22:42:44.983Z,1551998564.983 [WetLabsSeaOWL_UV_A] Loaded 2019-03-07T22:42:44.983Z,1551998564.983 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2019-03-07T22:42:44.984Z,1551998564.984 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0 2019-03-07T22:42:44.984Z,1551998564.984 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7531 2019-03-07T22:42:44.985Z,1551998564.985 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-03-07T22:42:44.986Z,1551998564.986 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-03-07T22:42:45.231Z,1551998565.231 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-03-07T22:42:45.231Z,1551998565.231 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-03-07T22:42:45.273Z,1551998565.273 [DepthRateCalculator] Loaded 2019-03-07T22:42:45.273Z,1551998565.273 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-03-07T22:42:45.279Z,1551998565.279 [PitchRateCalculator] Loaded 2019-03-07T22:42:45.279Z,1551998565.279 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-03-07T22:42:45.292Z,1551998565.292 [SpeedCalculator] Loaded 2019-03-07T22:42:45.292Z,1551998565.292 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-03-07T22:42:45.313Z,1551998565.313 [TempGradientCalculator] Loaded 2019-03-07T22:42:45.314Z,1551998565.314 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-03-07T22:42:45.319Z,1551998565.319 [YawRateCalculator] Loaded 2019-03-07T22:42:45.320Z,1551998565.320 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-03-07T22:42:45.361Z,1551998565.361 [ElevatorOffsetCalculator] Loaded 2019-03-07T22:42:45.361Z,1551998565.361 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-03-07T22:42:45.362Z,1551998565.362 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-03-07T22:42:45.362Z,1551998565.362 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-03-07T22:42:45.500Z,1551998565.500 [SBIT](DEBUG): Construct Startup Built In Test. 2019-03-07T22:42:45.523Z,1551998565.523 [SBIT] Loaded 2019-03-07T22:42:45.523Z,1551998565.523 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-03-07T22:42:45.524Z,1551998565.524 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-03-07T22:42:45.535Z,1551998565.535 [IBIT] Loaded 2019-03-07T22:42:45.536Z,1551998565.536 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-03-07T22:42:45.539Z,1551998565.539 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-03-07T22:42:45.679Z,1551998565.679 [CBIT] Loaded 2019-03-07T22:42:45.679Z,1551998565.679 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-03-07T22:42:45.679Z,1551998565.679 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-03-07T22:42:45.680Z,1551998565.680 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-03-07T22:42:45.744Z,1551998565.744 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-03-07T22:42:45.745Z,1551998565.745 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-03-07T22:42:45.846Z,1551998565.846 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-03-07T22:42:45.847Z,1551998565.847 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-03-07T22:42:46.025Z,1551998566.025 [VerticalControl](DEBUG): Construct VerticalControl. 2019-03-07T22:42:46.120Z,1551998566.120 [VerticalControl] Loaded 2019-03-07T22:42:46.120Z,1551998566.120 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-03-07T22:42:46.121Z,1551998566.121 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-03-07T22:42:46.179Z,1551998566.179 [HorizontalControl] Loaded 2019-03-07T22:42:46.179Z,1551998566.179 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-03-07T22:42:46.180Z,1551998566.180 [SpeedControl](DEBUG): Construct SpeedControl. 2019-03-07T22:42:46.182Z,1551998566.182 [SpeedControl] Loaded 2019-03-07T22:42:46.182Z,1551998566.182 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-03-07T22:42:46.183Z,1551998566.183 [LoopControl](DEBUG): Construct LoopControl. 2019-03-07T22:42:46.183Z,1551998566.183 [LoopControl] Loaded 2019-03-07T22:42:46.183Z,1551998566.183 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-03-07T22:42:46.184Z,1551998566.184 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-03-07T22:42:46.184Z,1551998566.184 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-03-07T22:42:46.212Z,1551998566.212 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-03-07T22:42:46.216Z,1551998566.216 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-03-07T22:42:46.217Z,1551998566.217 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-03-07T22:42:46.223Z,1551998566.223 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-03-07T22:42:46.224Z,1551998566.224 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0 2019-03-07T22:42:46.225Z,1551998566.225 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7532 2019-03-07T22:42:46.229Z,1551998566.229 [Supervisor](INFO): Main Thread ID is 7444 2019-03-07T22:42:46.230Z,1551998566.230 [Supervisor](DEBUG): Running supervisor. 2019-03-07T22:42:46.230Z,1551998566.230 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7533 2019-03-07T22:42:46.233Z,1551998566.233 [controlThread ThreadHandler](INFO): Handler Thread ID is 7534 2019-03-07T22:42:46.233Z,1551998566.233 [controlThread](DEBUG): Initializing ControlThread 2019-03-07T22:42:46.240Z,1551998566.240 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-07T22:42:46.241Z,1551998566.241 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-03-07T22:42:46.241Z,1551998566.241 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-03-07T22:42:46.243Z,1551998566.243 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-03-07T22:42:46.243Z,1551998566.243 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-03-07T22:42:46.243Z,1551998566.243 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-03-07T22:42:46.243Z,1551998566.243 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-03-07T22:42:46.244Z,1551998566.244 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-03-07T22:42:46.244Z,1551998566.244 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-03-07T22:42:46.245Z,1551998566.245 [SBIT](INFO): Initialize SBIT Component. 2019-03-07T22:42:46.246Z,1551998566.246 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3 2019-03-07T22:42:46.246Z,1551998566.246 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa 2019-03-07T22:42:46.246Z,1551998566.246 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-03-07T22:42:46.247Z,1551998566.247 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-03-07T22:42:46.248Z,1551998566.248 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-03-07T22:42:46.248Z,1551998566.248 [IBIT](INFO): Initialize IBIT Component. 2019-03-07T22:42:46.249Z,1551998566.249 [CBIT](DEBUG): Initialize CBIT Component. 2019-03-07T22:42:46.250Z,1551998566.250 [logger ThreadHandler](INFO): Handler Thread ID is 7535 2019-03-07T22:42:46.261Z,1551998566.261 [CBIT](DEBUG): Initialized mux pins. 2019-03-07T22:42:46.261Z,1551998566.261 [CBIT](DEBUG): Initializing the watchdog timer. 2019-03-07T22:42:46.269Z,1551998566.269 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7536 2019-03-07T22:42:46.270Z,1551998566.270 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-03-07T22:42:46.281Z,1551998566.281 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7537 2019-03-07T22:42:46.285Z,1551998566.285 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2019-03-07T22:42:46.285Z,1551998566.285 [CBIT](DEBUG): Initializing heartbeat. 2019-03-07T22:42:46.293Z,1551998566.293 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7538 2019-03-07T22:42:46.294Z,1551998566.294 [CTD_NeilBrown](INFO): Powering down 2019-03-07T22:42:46.329Z,1551998566.329 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7539 2019-03-07T22:42:46.330Z,1551998566.330 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-03-07T22:42:46.357Z,1551998566.357 [CBIT](DEBUG): Deactivating GF circuits. 2019-03-07T22:42:46.357Z,1551998566.357 [CBIT](DEBUG): Deactivating emergency mode. 2019-03-07T22:42:46.358Z,1551998566.358 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7540 2019-03-07T22:42:46.361Z,1551998566.361 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-07T22:42:46.361Z,1551998566.361 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-03-07T22:42:46.361Z,1551998566.361 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-07T22:42:46.361Z,1551998566.361 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-03-07T22:42:46.361Z,1551998566.361 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-07T22:42:46.362Z,1551998566.362 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-03-07T22:42:46.362Z,1551998566.362 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-07T22:42:46.362Z,1551998566.362 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-03-07T22:42:46.362Z,1551998566.362 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-07T22:42:46.362Z,1551998566.362 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-03-07T22:42:46.362Z,1551998566.362 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-07T22:42:46.363Z,1551998566.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-03-07T22:42:46.363Z,1551998566.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-07T22:42:46.363Z,1551998566.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-03-07T22:42:46.363Z,1551998566.363 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-07T22:42:46.363Z,1551998566.363 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-03-07T22:42:46.393Z,1551998566.393 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-03-07T22:42:46.395Z,1551998566.395 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-03-07T22:42:46.395Z,1551998566.395 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-03-07T22:42:46.396Z,1551998566.396 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-03-07T22:42:46.397Z,1551998566.397 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-03-07T22:42:46.406Z,1551998566.406 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-07T22:42:46.437Z,1551998566.437 [MissionManager](DEBUG): 2019-03-07T22:42:46.438Z,1551998566.438 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-03-07T22:42:46.505Z,1551998566.505 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-03-07T22:42:46.506Z,1551998566.506 [Default:A.Wait](DEBUG): Construct Wait. 2019-03-07T22:42:46.508Z,1551998566.508 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-07T22:42:46.534Z,1551998566.534 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-03-07T22:42:46.550Z,1551998566.550 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-03-07T22:42:46.555Z,1551998566.555 [Default:E.Execute](DEBUG): Construct Execute. 2019-03-07T22:42:46.567Z,1551998566.567 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2019-03-07T22:42:46.584Z,1551998566.584 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-07T22:42:46.626Z,1551998566.626 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-03-07T22:42:46.627Z,1551998566.627 [DUSBL_Hydroid](INFO): Powering up 2019-03-07T22:42:46.627Z,1551998566.627 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-03-07T22:42:46.645Z,1551998566.645 [Micromodem](INFO): Start 2019-03-07T22:42:46.685Z,1551998566.685 [Radio_Surface](INFO): Powering up 2019-03-07T22:42:46.721Z,1551998566.721 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-07T22:42:46.750Z,1551998566.750 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-03-07T22:42:46.761Z,1551998566.761 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-03-07T22:42:46.762Z,1551998566.762 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-07T22:42:46.773Z,1551998566.773 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-07T22:42:46.774Z,1551998566.774 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-07T22:42:46.781Z,1551998566.781 [MassServo](DEBUG): Initializing MassServo. 2019-03-07T22:42:46.782Z,1551998566.782 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-07T22:42:46.789Z,1551998566.789 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-07T22:42:46.790Z,1551998566.790 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-03-07T22:42:46.797Z,1551998566.797 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-03-07T22:42:47.010Z,1551998567.010 [Micromodem](INFO): Starting 2019-03-07T22:42:47.011Z,1551998567.011 [Micromodem](INFO): Powering up 2019-03-07T22:42:47.011Z,1551998567.011 [Micromodem](DEBUG): Initializing Micromodem. 2019-03-07T22:42:47.117Z,1551998567.117 [RDI_PathfinderUp](INFO): Powering down 2019-03-07T22:42:47.173Z,1551998567.173 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-07T22:42:47.402Z,1551998567.402 [Micromodem](INFO): Starting 2019-03-07T22:42:47.421Z,1551998567.421 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-07T22:42:47.741Z,1551998567.741 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-03-07T22:42:47.741Z,1551998567.741 [RudderServo](FAULT): Rudder failed to initialize 2019-03-07T22:42:47.741Z,1551998567.741 [RudderServo] Communications Fault, FailCount= 1 2019-03-07T22:42:47.741Z,1551998567.741 [RudderServo](ERROR): Communications Fault 2019-03-07T22:42:47.855Z,1551998567.855 [CBIT](ERROR): Communications Fault in component: RudderServo 2019-03-07T22:42:47.863Z,1551998567.863 [Micromodem](INFO): Starting 2019-03-07T22:42:48.014Z,1551998568.014 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-07T22:42:48.014Z,1551998568.014 [RudderServo](INFO): Powering down 2019-03-07T22:42:48.266Z,1551998568.266 [Micromodem](INFO): Starting 2019-03-07T22:42:48.678Z,1551998568.678 [Micromodem](INFO): Starting 2019-03-07T22:42:48.731Z,1551998568.731 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-07T22:42:48.850Z,1551998568.850 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-07T22:42:48.854Z,1551998568.854 [CBIT](INFO): Clearing failed state for component RudderServo 2019-03-07T22:42:48.854Z,1551998568.854 [RudderServo] No Fault, FailCount= 1 2019-03-07T22:42:49.074Z,1551998569.074 [Micromodem](INFO): Starting 2019-03-07T22:42:49.478Z,1551998569.478 [Micromodem](INFO): Starting 2019-03-07T22:42:49.883Z,1551998569.883 [Micromodem](INFO): Starting 2019-03-07T22:42:50.021Z,1551998570.021 [Aanderaa_O2](INFO): Powering down 2019-03-07T22:42:50.286Z,1551998570.286 [Micromodem](INFO): Starting 2019-03-07T22:42:50.690Z,1551998570.690 [Micromodem](INFO): Starting 2019-03-07T22:42:51.094Z,1551998571.094 [Micromodem](INFO): Starting 2019-03-07T22:42:51.498Z,1551998571.498 [Micromodem](INFO): Starting 2019-03-07T22:42:51.902Z,1551998571.902 [Micromodem](INFO): Starting 2019-03-07T22:42:51.903Z,1551998571.903 [Micromodem](INFO): Starting 2019-03-07T22:42:51.903Z,1551998571.903 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37 2019-03-07T22:42:51.903Z,1551998571.903 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,CST,1*37 2019-03-07T22:42:51.903Z,1551998571.903 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37 2019-03-07T22:42:51.904Z,1551998571.904 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31 2019-03-07T22:42:51.904Z,1551998571.904 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31 2019-03-07T22:42:51.904Z,1551998571.904 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32 2019-03-07T22:42:51.904Z,1551998571.904 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,REV,1*32 2019-03-07T22:42:51.904Z,1551998571.904 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29 2019-03-07T22:42:51.906Z,1551998571.906 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,RXP,1*29 2019-03-07T22:42:52.306Z,1551998572.306 [Micromodem](INFO): Starting 2019-03-07T22:42:52.834Z,1551998572.834 [Micromodem](INFO): Starting 2019-03-07T22:42:53.215Z,1551998573.215 [Micromodem](INFO): Starting 2019-03-07T22:42:53.216Z,1551998573.216 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:42:53.216Z,1551998573.216 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2019-03-07T22:42:53.626Z,1551998573.626 [Micromodem](INFO): Starting 2019-03-07T22:42:54.030Z,1551998574.030 [Micromodem](INFO): Starting 2019-03-07T22:42:54.435Z,1551998574.435 [Micromodem](INFO): Starting 2019-03-07T22:42:54.839Z,1551998574.839 [Micromodem](INFO): Starting 2019-03-07T22:42:55.242Z,1551998575.242 [Micromodem](INFO): Starting 2019-03-07T22:42:55.646Z,1551998575.646 [Micromodem](INFO): Starting 2019-03-07T22:42:56.051Z,1551998576.051 [Micromodem](INFO): Starting 2019-03-07T22:42:56.451Z,1551998576.451 [Micromodem](INFO): Starting 2019-03-07T22:42:56.858Z,1551998576.858 [Micromodem](INFO): Starting 2019-03-07T22:42:57.271Z,1551998577.271 [Micromodem](INFO): Starting 2019-03-07T22:42:57.671Z,1551998577.671 [Micromodem](INFO): Starting 2019-03-07T22:42:58.067Z,1551998578.067 [Micromodem](INFO): Starting 2019-03-07T22:42:58.206Z,1551998578.206 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004333 2019-03-07T22:42:58.483Z,1551998578.483 [Micromodem](INFO): Starting 2019-03-07T22:42:58.875Z,1551998578.875 [Micromodem](INFO): Starting 2019-03-07T22:42:59.279Z,1551998579.279 [Micromodem](INFO): Starting 2019-03-07T22:42:59.694Z,1551998579.694 [Micromodem](INFO): Starting 2019-03-07T22:43:00.087Z,1551998580.087 [Micromodem](INFO): Starting 2019-03-07T22:43:00.112Z,1551998580.112 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-03-07T22:43:00.491Z,1551998580.491 [Micromodem](INFO): Starting 2019-03-07T22:43:00.907Z,1551998580.907 [Micromodem](INFO): Starting 2019-03-07T22:43:01.299Z,1551998581.299 [Micromodem](INFO): Starting 2019-03-07T22:43:01.703Z,1551998581.703 [Micromodem](INFO): Starting 2019-03-07T22:43:02.119Z,1551998582.119 [Micromodem](INFO): Starting 2019-03-07T22:43:02.511Z,1551998582.511 [Micromodem](INFO): Starting 2019-03-07T22:43:02.915Z,1551998582.915 [Micromodem](INFO): Starting 2019-03-07T22:43:03.331Z,1551998583.331 [Micromodem](INFO): Starting 2019-03-07T22:43:03.331Z,1551998583.331 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:03.331Z,1551998583.331 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32 2019-03-07T22:43:03.724Z,1551998583.724 [Micromodem](INFO): Starting 2019-03-07T22:43:04.128Z,1551998584.128 [Micromodem](INFO): Starting 2019-03-07T22:43:04.543Z,1551998584.543 [DUSBL_Hydroid](INFO): DUSBL Version:O 2019-03-07T22:43:04.557Z,1551998584.557 [Micromodem](INFO): Starting 2019-03-07T22:43:04.935Z,1551998584.935 [Micromodem](INFO): Starting 2019-03-07T22:43:05.339Z,1551998585.339 [Micromodem](INFO): Starting 2019-03-07T22:43:05.754Z,1551998585.754 [Micromodem](INFO): Starting 2019-03-07T22:43:06.147Z,1551998586.147 [Micromodem](INFO): Starting 2019-03-07T22:43:06.550Z,1551998586.550 [Micromodem](INFO): Starting 2019-03-07T22:43:06.967Z,1551998586.967 [Micromodem](INFO): Starting 2019-03-07T22:43:07.359Z,1551998587.359 [Micromodem](INFO): Starting 2019-03-07T22:43:07.762Z,1551998587.762 [Micromodem](INFO): Starting 2019-03-07T22:43:08.182Z,1551998588.182 [Micromodem](INFO): Starting 2019-03-07T22:43:08.570Z,1551998588.570 [Micromodem](INFO): Starting 2019-03-07T22:43:08.974Z,1551998588.974 [Micromodem](INFO): Starting 2019-03-07T22:43:09.382Z,1551998589.382 [Micromodem](INFO): Starting 2019-03-07T22:43:09.783Z,1551998589.783 [Micromodem](INFO): Starting 2019-03-07T22:43:10.186Z,1551998590.186 [Micromodem](INFO): Starting 2019-03-07T22:43:10.603Z,1551998590.603 [Micromodem](INFO): Starting 2019-03-07T22:43:10.994Z,1551998590.994 [Micromodem](INFO): Starting 2019-03-07T22:43:11.398Z,1551998591.398 [Micromodem](INFO): Starting 2019-03-07T22:43:11.815Z,1551998591.815 [Micromodem](INFO): Starting 2019-03-07T22:43:12.206Z,1551998592.206 [Micromodem](INFO): Starting 2019-03-07T22:43:12.622Z,1551998592.622 [Micromodem](INFO): Starting 2019-03-07T22:43:12.623Z,1551998592.623 [NAL9602](INFO): Powering up NAL9602 2019-03-07T22:43:13.030Z,1551998593.030 [Micromodem](INFO): Starting 2019-03-07T22:43:13.031Z,1551998593.031 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:13.031Z,1551998593.031 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29 2019-03-07T22:43:13.422Z,1551998593.422 [Micromodem](INFO): Starting 2019-03-07T22:43:13.823Z,1551998593.823 [Micromodem](INFO): Starting 2019-03-07T22:43:14.234Z,1551998594.234 [Micromodem](INFO): Starting 2019-03-07T22:43:14.630Z,1551998594.630 [Micromodem](INFO): Starting 2019-03-07T22:43:14.659Z,1551998594.659 [SBIT](IMPORTANT): Beginning Startup BIT 2019-03-07T22:43:14.704Z,1551998594.704 [CBIT](IMPORTANT): Beginning ground fault scan 2019-03-07T22:43:15.034Z,1551998595.034 [Micromodem](INFO): Starting 2019-03-07T22:43:15.446Z,1551998595.446 [Micromodem](INFO): Starting 2019-03-07T22:43:15.853Z,1551998595.853 [Micromodem](INFO): Starting 2019-03-07T22:43:16.246Z,1551998596.246 [Micromodem](INFO): Starting 2019-03-07T22:43:16.658Z,1551998596.658 [Micromodem](INFO): Starting 2019-03-07T22:43:17.062Z,1551998597.062 [Micromodem](INFO): Starting 2019-03-07T22:43:17.656Z,1551998597.656 [Micromodem](INFO): Starting 2019-03-07T22:43:18.070Z,1551998598.070 [Micromodem](INFO): Starting 2019-03-07T22:43:18.466Z,1551998598.466 [Micromodem](INFO): Starting 2019-03-07T22:43:18.870Z,1551998598.870 [Micromodem](INFO): Starting 2019-03-07T22:43:19.274Z,1551998599.274 [Micromodem](INFO): Starting 2019-03-07T22:43:19.678Z,1551998599.678 [Micromodem](INFO): Starting 2019-03-07T22:43:20.082Z,1551998600.082 [Micromodem](INFO): Starting 2019-03-07T22:43:20.486Z,1551998600.486 [Micromodem](INFO): Starting 2019-03-07T22:43:20.890Z,1551998600.890 [Micromodem](INFO): Starting 2019-03-07T22:43:21.294Z,1551998601.294 [Micromodem](INFO): Starting 2019-03-07T22:43:21.698Z,1551998601.698 [Micromodem](INFO): Starting 2019-03-07T22:43:22.102Z,1551998602.102 [Micromodem](INFO): Starting 2019-03-07T22:43:22.506Z,1551998602.506 [Micromodem](INFO): Starting 2019-03-07T22:43:22.910Z,1551998602.910 [Micromodem](INFO): Starting 2019-03-07T22:43:23.314Z,1551998603.314 [Micromodem](INFO): Starting 2019-03-07T22:43:23.315Z,1551998603.315 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:23.316Z,1551998603.316 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-07T22:43:24Z,0*7B 2019-03-07T22:43:23.316Z,1551998603.316 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-07T22:43:24Z,0*7B 2019-03-07T22:43:23.316Z,1551998603.316 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-07T22:43:24Z,0*7B 2019-03-07T22:43:23.718Z,1551998603.718 [Micromodem](INFO): Starting 2019-03-07T22:43:23.720Z,1551998603.720 [NAL9602](INFO): NAL9602 initialized 2019-03-07T22:43:24.122Z,1551998604.122 [Micromodem](INFO): Starting 2019-03-07T22:43:24.526Z,1551998604.526 [Micromodem](INFO): Starting 2019-03-07T22:43:24.527Z,1551998604.527 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:24.527Z,1551998604.527 [Micromodem](DEBUG): Nmea buf: $CCCFQ,TOP*33 2019-03-07T22:43:24.527Z,1551998604.527 [Micromodem](IMPORTANT): Nmea buf: $CCCFQ,TOP*33 2019-03-07T22:43:24.528Z,1551998604.528 [Micromodem](INFO): Nmea out: $CCCFQ,TOP*33 2019-03-07T22:43:24.545Z,1551998604.545 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:43:24.931Z,1551998604.931 [Micromodem](INFO): Starting 2019-03-07T22:43:25.335Z,1551998605.335 [Micromodem](INFO): Starting 2019-03-07T22:43:25.338Z,1551998605.338 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:25.342Z,1551998605.342 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:25.346Z,1551998605.346 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:25.601Z,1551998605.601 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010992 CHAN A1 (24V): -0.026236 CHAN A2 (12V): -0.007176 CHAN A3 (5V): -0.002030 CHAN B0 (3.3V): 0.000035 CHAN B1 (3.15aV): -0.000661 CHAN B2 (3.15bV): -0.000307 CHAN B3 (GND): 0.001177 OPEN: 0.009154 Full Scale Calc: 4.765 mA, -1.589 mA 2019-03-07T22:43:25.745Z,1551998605.745 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:25.786Z,1551998605.786 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:26.149Z,1551998606.149 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:26.193Z,1551998606.193 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:26.553Z,1551998606.553 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:26.560Z,1551998606.560 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:26.998Z,1551998606.998 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:27.022Z,1551998607.022 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:27.366Z,1551998607.366 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:27.420Z,1551998607.420 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:27.766Z,1551998607.766 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:27.774Z,1551998607.774 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:28.168Z,1551998608.168 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:28.175Z,1551998608.175 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:28.574Z,1551998608.574 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:28.581Z,1551998608.581 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:28.978Z,1551998608.978 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:28.987Z,1551998608.987 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:29.385Z,1551998609.385 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:29.394Z,1551998609.394 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:29.785Z,1551998609.785 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:29.792Z,1551998609.792 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:30.428Z,1551998610.428 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:30.451Z,1551998610.451 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:30.845Z,1551998610.845 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:30.876Z,1551998610.876 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:31.237Z,1551998611.237 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:31.244Z,1551998611.244 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:31.640Z,1551998611.640 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:31.647Z,1551998611.647 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:32.044Z,1551998612.044 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:32.060Z,1551998612.060 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:32.448Z,1551998612.448 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:32.455Z,1551998612.455 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:32.873Z,1551998612.873 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:32.892Z,1551998612.892 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:33.257Z,1551998613.257 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:33.263Z,1551998613.263 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:33.660Z,1551998613.660 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:33.667Z,1551998613.667 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:34.064Z,1551998614.064 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:34.071Z,1551998614.071 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:34.468Z,1551998614.468 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:34.475Z,1551998614.475 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:34.877Z,1551998614.877 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:34.884Z,1551998614.884 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:35.284Z,1551998615.284 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:35.299Z,1551998615.299 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:35.680Z,1551998615.680 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:35.697Z,1551998615.697 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:36.084Z,1551998616.084 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:36.091Z,1551998616.091 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:36.496Z,1551998616.496 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:36.511Z,1551998616.511 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:36.893Z,1551998616.893 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:36.899Z,1551998616.899 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:37.296Z,1551998617.296 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:37.304Z,1551998617.304 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:37.700Z,1551998617.700 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:37.707Z,1551998617.707 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:38.104Z,1551998618.104 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:38.116Z,1551998618.116 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:38.513Z,1551998618.513 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:38.529Z,1551998618.529 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:38.920Z,1551998618.920 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:38.941Z,1551998618.941 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:39.338Z,1551998619.338 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:39.355Z,1551998619.355 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:39.723Z,1551998619.723 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:39.733Z,1551998619.733 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:40.128Z,1551998620.128 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:40.139Z,1551998620.139 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:40.532Z,1551998620.532 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:40.542Z,1551998620.542 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:40.936Z,1551998620.936 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:40.947Z,1551998620.947 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:41.340Z,1551998621.340 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:41.359Z,1551998621.359 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:41.745Z,1551998621.745 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:41.756Z,1551998621.756 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:42.165Z,1551998622.165 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:42.189Z,1551998622.189 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:42.556Z,1551998622.556 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:42.567Z,1551998622.567 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:42.959Z,1551998622.959 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:42.996Z,1551998622.996 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:43.359Z,1551998623.359 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:43.369Z,1551998623.369 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:43.763Z,1551998623.763 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:43.776Z,1551998623.776 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:44.173Z,1551998624.173 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:44.188Z,1551998624.188 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:44.572Z,1551998624.572 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:44.584Z,1551998624.584 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:44.979Z,1551998624.979 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:44.996Z,1551998624.996 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:45.382Z,1551998625.382 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:45.390Z,1551998625.390 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:45.787Z,1551998625.787 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:45.802Z,1551998625.802 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:46.190Z,1551998626.190 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:46.230Z,1551998626.230 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:46.596Z,1551998626.596 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:46.626Z,1551998626.626 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:47.001Z,1551998627.001 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:47.024Z,1551998627.024 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:47.402Z,1551998627.402 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:47.433Z,1551998627.433 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:47.811Z,1551998627.811 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:47.871Z,1551998627.871 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:48.212Z,1551998628.212 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:48.234Z,1551998628.234 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:48.615Z,1551998628.615 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:48.640Z,1551998628.640 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:49.016Z,1551998629.016 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:49.037Z,1551998629.037 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:49.423Z,1551998629.423 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:49.442Z,1551998629.442 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:49.861Z,1551998629.861 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:49.886Z,1551998629.886 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:50.247Z,1551998630.247 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:50.283Z,1551998630.283 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:50.632Z,1551998630.632 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:50.650Z,1551998630.650 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:51.036Z,1551998631.036 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:51.059Z,1551998631.059 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:51.443Z,1551998631.443 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:51.459Z,1551998631.459 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:51.878Z,1551998631.878 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:51.901Z,1551998631.901 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:52.248Z,1551998632.248 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:52.261Z,1551998632.261 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:52.660Z,1551998632.660 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:52.691Z,1551998632.691 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:53.062Z,1551998633.062 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:53.083Z,1551998633.083 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:53.463Z,1551998633.463 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:53.484Z,1551998633.484 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:53.876Z,1551998633.876 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:53.887Z,1551998633.887 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:54.275Z,1551998634.275 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:54.288Z,1551998634.288 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:54.672Z,1551998634.672 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:54.683Z,1551998634.683 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:55.078Z,1551998635.078 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:55.088Z,1551998635.088 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:55.481Z,1551998635.481 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:55.492Z,1551998635.492 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:55.893Z,1551998635.893 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:55.903Z,1551998635.903 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:56.296Z,1551998636.296 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:56.317Z,1551998636.317 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:56.693Z,1551998636.693 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:56.704Z,1551998636.704 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:57.218Z,1551998637.218 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:57.229Z,1551998637.229 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:57.623Z,1551998637.623 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:57.637Z,1551998637.637 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:58.022Z,1551998638.022 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:58.050Z,1551998638.050 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:58.423Z,1551998638.423 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:58.436Z,1551998638.436 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:58.873Z,1551998638.873 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:58.899Z,1551998638.899 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:59.252Z,1551998639.252 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:59.267Z,1551998639.267 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:59.635Z,1551998639.635 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:43:59.644Z,1551998639.644 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:00.053Z,1551998640.053 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:00.091Z,1551998640.091 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:00.447Z,1551998640.447 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:00.468Z,1551998640.468 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:00.879Z,1551998640.879 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:00.917Z,1551998640.917 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:01.254Z,1551998641.254 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:01.270Z,1551998641.270 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:01.658Z,1551998641.658 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:01.675Z,1551998641.675 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:02.062Z,1551998642.062 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:02.074Z,1551998642.074 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:02.471Z,1551998642.471 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:02.481Z,1551998642.481 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:02.882Z,1551998642.882 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:02.894Z,1551998642.894 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:03.278Z,1551998643.278 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:03.287Z,1551998643.287 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:03.681Z,1551998643.681 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:03.692Z,1551998643.692 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:04.079Z,1551998644.079 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:04.096Z,1551998644.096 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:04.489Z,1551998644.489 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:04.500Z,1551998644.500 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:04.884Z,1551998644.884 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:04.891Z,1551998644.891 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:05.294Z,1551998645.294 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:05.304Z,1551998645.304 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:05.697Z,1551998645.697 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:05.704Z,1551998645.704 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:06.102Z,1551998646.102 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:06.108Z,1551998646.108 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:06.504Z,1551998646.504 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:06.512Z,1551998646.512 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:06.911Z,1551998646.911 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:06.918Z,1551998646.918 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:07.313Z,1551998647.313 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:07.320Z,1551998647.320 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:07.717Z,1551998647.717 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:07.728Z,1551998647.728 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:08.119Z,1551998648.119 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:08.127Z,1551998648.127 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:08.183Z,1551998648.183 [SBIT](IMPORTANT): SBIT PASSED 2019-03-07T22:44:08.230Z,1551998648.230 [CommandLine](IMPORTANT): got command configSet list 2019-03-07T22:44:08.231Z,1551998648.231 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-03-07T22:44:08.232Z,1551998648.232 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2019-03-07T22:44:08.232Z,1551998648.232 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity=1 enum; 2019-03-07T22:44:08.528Z,1551998648.528 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:08.534Z,1551998648.534 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:08.574Z,1551998648.574 [MissionManager](IMPORTANT): Started mission Startup 2019-03-07T22:44:08.575Z,1551998648.575 [Startup] Running Loop=1 2019-03-07T22:44:08.575Z,1551998648.575 [Startup](DEBUG): Aggregate::initialize Startup 2019-03-07T22:44:08.575Z,1551998648.575 [Startup:A.GoToSurface] Running Loop=1 2019-03-07T22:44:08.575Z,1551998648.575 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-07T22:44:08.575Z,1551998648.575 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-07T22:44:08.576Z,1551998648.576 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-07T22:44:08.576Z,1551998648.576 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-07T22:44:08.577Z,1551998648.577 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-07T22:44:08.577Z,1551998648.577 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-07T22:44:08.579Z,1551998648.579 [Startup:StartupSatComms] Running Loop=1 2019-03-07T22:44:08.579Z,1551998648.579 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-03-07T22:44:08.579Z,1551998648.579 [Startup:StartupSatComms:A] Running Loop=1 2019-03-07T22:44:08.930Z,1551998648.930 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:08.938Z,1551998648.938 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:08.970Z,1551998648.970 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-03-07T22:44:09.332Z,1551998649.332 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:09.348Z,1551998649.348 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:09.731Z,1551998649.731 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:09.737Z,1551998649.737 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:10.136Z,1551998650.136 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:10.143Z,1551998650.143 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:10.540Z,1551998650.540 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:10.546Z,1551998650.546 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:10.943Z,1551998650.943 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:10.949Z,1551998650.949 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:11.351Z,1551998651.351 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:11.358Z,1551998651.358 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:11.754Z,1551998651.754 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:11.759Z,1551998651.759 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:12.154Z,1551998652.154 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:12.167Z,1551998652.167 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:12.562Z,1551998652.562 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:12.567Z,1551998652.567 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:12.966Z,1551998652.966 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:12.973Z,1551998652.973 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:13.367Z,1551998653.367 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:13.373Z,1551998653.373 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:13.775Z,1551998653.775 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:13.782Z,1551998653.782 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:14.176Z,1551998654.176 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:14.181Z,1551998654.181 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:14.580Z,1551998654.580 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:14.586Z,1551998654.586 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:14.986Z,1551998654.986 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:14.993Z,1551998654.993 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:15.392Z,1551998655.392 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:15.397Z,1551998655.397 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:15.791Z,1551998655.791 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:15.796Z,1551998655.796 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:16.198Z,1551998656.198 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:16.204Z,1551998656.204 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:16.599Z,1551998656.599 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:16.603Z,1551998656.603 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:17.002Z,1551998657.002 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:17.006Z,1551998657.006 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:17.405Z,1551998657.405 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:17.409Z,1551998657.409 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:17.811Z,1551998657.811 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:17.814Z,1551998657.814 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:18.216Z,1551998658.216 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:18.219Z,1551998658.219 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:18.622Z,1551998658.622 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:18.625Z,1551998658.625 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:19.024Z,1551998659.024 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:19.026Z,1551998659.026 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:19.424Z,1551998659.424 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:19.426Z,1551998659.426 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:19.836Z,1551998659.836 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:19.841Z,1551998659.841 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:20.236Z,1551998660.236 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:20.238Z,1551998660.238 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:20.640Z,1551998660.640 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:20.641Z,1551998660.641 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:21.047Z,1551998661.047 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:21.050Z,1551998661.050 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:21.443Z,1551998661.443 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:21.445Z,1551998661.445 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:21.856Z,1551998661.856 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:21.859Z,1551998661.859 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:22.252Z,1551998662.252 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:22.254Z,1551998662.254 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:22.655Z,1551998662.655 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:22.657Z,1551998662.657 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:23.063Z,1551998663.063 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:23.065Z,1551998663.065 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:23.463Z,1551998663.463 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:23.465Z,1551998663.465 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:23.871Z,1551998663.871 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:23.872Z,1551998663.872 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:24.271Z,1551998664.271 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:33.163Z,1551998673.163 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:33.164Z,1551998673.164 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:41.240Z,1551998681.240 [NAL9602](INFO): SBD MO Status=2, MOMSN=2189, MT Status=2, MTMSN=0 2019-03-07T22:44:41.240Z,1551998681.240 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-03-07T22:44:43.259Z,1551998683.259 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:43.260Z,1551998683.260 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:46.520Z,1551998686.520 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:44:46.520Z,1551998686.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-07T22:44:46.520Z,1551998686.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-07T22:44:46.522Z,1551998686.522 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:44:46.522Z,1551998686.522 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-07T22:44:46.522Z,1551998686.522 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-07T22:44:46.523Z,1551998686.523 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:44:46.523Z,1551998686.523 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-03-07T22:44:46.524Z,1551998686.524 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-07T22:44:46.575Z,1551998686.575 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-07T22:44:46.575Z,1551998686.575 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-07T22:44:46.575Z,1551998686.575 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-07T22:44:46.938Z,1551998686.938 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-07T22:44:46.939Z,1551998686.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-07T22:44:46.939Z,1551998686.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-07T22:44:46.939Z,1551998686.939 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-07T22:44:46.940Z,1551998686.940 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-07T22:44:46.940Z,1551998686.940 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-03-07T22:44:47.328Z,1551998687.328 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-07T22:44:53.363Z,1551998693.363 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:53.364Z,1551998693.364 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:44:56.810Z,1551998696.810 [CommandLine](IMPORTANT): got command show stack 2019-03-07T22:44:56.810Z,1551998696.810 [CommandLine](IMPORTANT): Behavior Stack: 2019-03-07T22:44:56.811Z,1551998696.811 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface 2019-03-07T22:44:56.811Z,1551998696.811 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A 2019-03-07T22:44:58.846Z,1551998698.846 [CommandLine](IMPORTANT): got command failComponent 2019-03-07T22:44:58.847Z,1551998698.847 [CommandLine](IMPORTANT): Failed components: 2019-03-07T22:44:58.847Z,1551998698.847 [CommandLine](IMPORTANT): No failed Components. 2019-03-07T22:45:03.055Z,1551998703.055 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:03.056Z,1551998703.056 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:05.547Z,1551998705.547 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2019-03-07T22:45:08.735Z,1551998708.735 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-07T22:44:08.6Z 2019-03-07T22:45:08.735Z,1551998708.735 [Startup:StartupSatComms:A] Stopped 2019-03-07T22:45:08.735Z,1551998708.735 [Startup:StartupSatComms:B] Running Loop=1 2019-03-07T22:45:09.155Z,1551998709.155 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-07T22:45:13.275Z,1551998713.275 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:13.276Z,1551998713.276 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:13.796Z,1551998713.796 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190307T223257/Courier0004.lzma 2019-03-07T22:45:14.068Z,1551998714.068 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool 2019-03-07T22:45:14.115Z,1551998714.115 [Startup:StartupSatComms:B] Stopped 2019-03-07T22:45:14.115Z,1551998714.115 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-03-07T22:45:14.115Z,1551998714.115 [Startup:StartupSatComms] Stopped 2019-03-07T22:45:14.115Z,1551998714.115 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-03-07T22:45:14.116Z,1551998714.116 [Startup](INFO): Completed Startup 2019-03-07T22:45:14.116Z,1551998714.116 [MissionManager](INFO): Startup is completed. 2019-03-07T22:45:14.116Z,1551998714.116 [MissionManager](INFO): Uninitializing Mission Startup 2019-03-07T22:45:14.116Z,1551998714.116 [Startup] Stopped 2019-03-07T22:45:14.116Z,1551998714.116 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-03-07T22:45:14.117Z,1551998714.117 [Startup:A.GoToSurface] Stopped 2019-03-07T22:45:14.117Z,1551998714.117 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-07T22:45:14.438Z,1551998714.438 [DataOverHttps](INFO): Moved sent file to Logs/20190307T223257/Courier0004.lzma.bak 2019-03-07T22:45:14.439Z,1551998714.439 [DataOverHttps](INFO): SBD MOMSN=10096112 2019-03-07T22:45:14.579Z,1551998714.579 [MissionManager](IMPORTANT): Started mission Default 2019-03-07T22:45:14.579Z,1551998714.579 [Default] Running Loop=1 2019-03-07T22:45:14.579Z,1551998714.579 [Default](DEBUG): Aggregate::initialize Default 2019-03-07T22:45:14.580Z,1551998714.580 [Default:B.GoToSurface] Running Loop=1 2019-03-07T22:45:14.580Z,1551998714.580 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-07T22:45:14.580Z,1551998714.580 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-07T22:45:14.580Z,1551998714.580 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-07T22:45:14.581Z,1551998714.581 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-07T22:45:14.581Z,1551998714.581 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-07T22:45:14.582Z,1551998714.582 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-07T22:45:14.582Z,1551998714.582 [Default:A.Wait] Running Loop=1 2019-03-07T22:45:14.582Z,1551998714.582 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:45:17.899Z,1551998717.899 [CommandLine](IMPORTANT): got command failComponent 2019-03-07T22:45:17.899Z,1551998717.899 [CommandLine](IMPORTANT): Failed components: 2019-03-07T22:45:17.899Z,1551998717.899 [CommandLine](IMPORTANT): No failed Components. 2019-03-07T22:45:22.976Z,1551998722.976 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:23.379Z,1551998723.379 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:27.930Z,1551998727.930 [Default:A.Wait](INFO): Done Waiting. 2019-03-07T22:45:27.930Z,1551998727.930 [Default:A.Wait] Stopped 2019-03-07T22:45:27.930Z,1551998727.930 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:45:28.258Z,1551998728.258 [Default:CheckIn] Running Loop=1 2019-03-07T22:45:28.258Z,1551998728.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-07T22:45:28.258Z,1551998728.258 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-07T22:45:28.675Z,1551998728.675 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-03-07T22:45:33.072Z,1551998733.072 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:33.073Z,1551998733.073 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:33.080Z,1551998733.080 [NAL9602](INFO): SBD MO Status=2, MOMSN=2189, MT Status=2, MTMSN=0 2019-03-07T22:45:33.080Z,1551998733.080 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-03-07T22:45:43.175Z,1551998743.175 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:43.176Z,1551998743.176 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:53.271Z,1551998753.271 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:45:53.272Z,1551998753.272 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:03.375Z,1551998763.375 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:03.376Z,1551998763.376 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:07.450Z,1551998767.450 [CommandLine](IMPORTANT): got command show variable USBL 2019-03-07T22:46:07.506Z,1551998767.506 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool) 2019-03-07T22:46:07.506Z,1551998767.506 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool) 2019-03-07T22:46:07.507Z,1551998767.507 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second) 2019-03-07T22:46:07.507Z,1551998767.507 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second) 2019-03-07T22:46:07.507Z,1551998767.507 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count) 2019-03-07T22:46:07.508Z,1551998767.508 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond) 2019-03-07T22:46:07.508Z,1551998767.508 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond) 2019-03-07T22:46:07.508Z,1551998767.508 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum) 2019-03-07T22:46:07.509Z,1551998767.509 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count) 2019-03-07T22:46:07.509Z,1551998767.509 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum) 2019-03-07T22:46:07.510Z,1551998767.510 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree) 2019-03-07T22:46:07.510Z,1551998767.510 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree) 2019-03-07T22:46:07.519Z,1551998767.519 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none) 2019-03-07T22:46:07.520Z,1551998767.520 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none) 2019-03-07T22:46:07.520Z,1551998767.520 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second) 2019-03-07T22:46:07.523Z,1551998767.523 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum) 2019-03-07T22:46:07.523Z,1551998767.523 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree) 2019-03-07T22:46:07.524Z,1551998767.524 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree) 2019-03-07T22:46:07.524Z,1551998767.524 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond) 2019-03-07T22:46:07.524Z,1551998767.524 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond) 2019-03-07T22:46:07.525Z,1551998767.525 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio) 2019-03-07T22:46:07.525Z,1551998767.525 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio) 2019-03-07T22:46:07.526Z,1551998767.526 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio) 2019-03-07T22:46:07.526Z,1551998767.526 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter) 2019-03-07T22:46:07.526Z,1551998767.526 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none) 2019-03-07T22:46:13.067Z,1551998773.067 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:13.068Z,1551998773.068 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:14.231Z,1551998774.231 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.direction 2019-03-07T22:46:19.191Z,1551998779.191 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.range 2019-03-07T22:46:23.174Z,1551998783.174 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:23.174Z,1551998783.174 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:24.319Z,1551998784.319 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml 2019-03-07T22:46:24.319Z,1551998784.319 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml 2019-03-07T22:46:24.350Z,1551998784.350 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min 2019-03-07T22:46:24.354Z,1551998784.354 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count 2019-03-07T22:46:24.357Z,1551998784.357 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count 2019-03-07T22:46:24.361Z,1551998784.361 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count 2019-03-07T22:46:24.365Z,1551998784.365 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool 2019-03-07T22:46:24.367Z,1551998784.367 [DUSBL:A.Pitch](DEBUG): Construct. 2019-03-07T22:46:24.447Z,1551998784.447 [DUSBL:B.SetSpeed](DEBUG): Construct. 2019-03-07T22:46:24.464Z,1551998784.464 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait. 2019-03-07T22:46:24.476Z,1551998784.476 [MissionManager](DEBUG): This mission is requests a DUSBL arming. How long to let the mission run. 90 Number of pings to requst. 10 Transponder Address. 2 Number of pings requested each time. 1 Automatically set to true if the DUSBL is enabled. Set to false to disable reading from the DUSBL. 0 10 2019-03-07T22:46:24.483Z,1551998784.483 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml 2019-03-07T22:46:24.812Z,1551998784.812 [Default] Stopped 2019-03-07T22:46:24.813Z,1551998784.813 [Default](DEBUG): Aggregate::uninitialize Default 2019-03-07T22:46:24.813Z,1551998784.813 [Default:B.GoToSurface] Stopped 2019-03-07T22:46:24.813Z,1551998784.813 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-07T22:46:24.813Z,1551998784.813 [Default:CheckIn] Stopped 2019-03-07T22:46:24.813Z,1551998784.813 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-07T22:46:24.813Z,1551998784.813 [Default:CheckIn:Read_GPS] Stopped 2019-03-07T22:46:24.813Z,1551998784.813 [MissionManager](IMPORTANT): Started mission DUSBL 2019-03-07T22:46:24.814Z,1551998784.814 [DUSBL] Running Loop=1 2019-03-07T22:46:24.814Z,1551998784.814 [DUSBL](DEBUG): Aggregate::initialize DUSBL 2019-03-07T22:46:24.814Z,1551998784.814 [DUSBL:A.Pitch] Running Loop=1 2019-03-07T22:46:24.814Z,1551998784.814 [DUSBL:A.Pitch](DEBUG): Initialize. 2019-03-07T22:46:24.814Z,1551998784.814 [DUSBL:B.SetSpeed] Running Loop=1 2019-03-07T22:46:24.814Z,1551998784.814 [DUSBL:B.SetSpeed](DEBUG): Initialize. 2019-03-07T22:46:24.814Z,1551998784.814 [DUSBL:C] Running Loop=1 2019-03-07T22:46:24.815Z,1551998784.815 [DUSBL:RequestRepeater] Running Loop=1 2019-03-07T22:46:24.815Z,1551998784.815 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:46:24.815Z,1551998784.815 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:46:24.815Z,1551998784.815 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:46:24.815Z,1551998784.815 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:46:24.815Z,1551998784.815 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:46:24.815Z,1551998784.815 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:46:24.816Z,1551998784.816 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:46:24.816Z,1551998784.816 [DUSBL:C] Running Loop=1 2019-03-07T22:46:24.845Z,1551998784.845 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range 2019-03-07T22:46:24.847Z,1551998784.847 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction 2019-03-07T22:46:24.847Z,1551998784.847 [DUSBL:B.SetSpeed] Running Loop=1 2019-03-07T22:46:24.848Z,1551998784.848 [DUSBL:A.Pitch] Running Loop=1 2019-03-07T22:46:26.399Z,1551998786.399 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:26.399Z,1551998786.399 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:26.399Z,1551998786.399 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:26.399Z,1551998786.399 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:26.400Z,1551998786.400 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:26.400Z,1551998786.400 [Micromodem](INFO): IS COMMANDED IN Runnable 2019-03-07T22:46:26.403Z,1551998786.403 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54 2019-03-07T22:46:26.403Z,1551998786.403 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54 2019-03-07T22:46:26.404Z,1551998786.404 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,0,0,0,0,1000,0*54 2019-03-07T22:46:26.809Z,1551998786.809 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:27.228Z,1551998787.228 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:27.229Z,1551998787.229 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:27.611Z,1551998787.611 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:27.612Z,1551998787.612 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:30.444Z,1551998790.444 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:30.444Z,1551998790.444 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:30.444Z,1551998790.444 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:30.444Z,1551998790.444 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:30.445Z,1551998790.445 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:34.478Z,1551998794.478 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:34.479Z,1551998794.479 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:34.479Z,1551998794.479 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:34.479Z,1551998794.479 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:34.480Z,1551998794.480 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:34.984Z,1551998794.984 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:46:34.984Z,1551998794.984 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:46:34.984Z,1551998794.984 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:46:34.985Z,1551998794.985 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:46:34.985Z,1551998794.985 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:46:34.985Z,1551998794.985 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater](INFO): Running loop #2 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater] Running Loop=2 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:46:34.986Z,1551998794.986 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:46:36.907Z,1551998796.907 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:36.909Z,1551998796.909 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:38.522Z,1551998798.522 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:38.522Z,1551998798.522 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:38.523Z,1551998798.523 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:38.523Z,1551998798.523 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:38.524Z,1551998798.524 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:42.562Z,1551998802.562 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:42.562Z,1551998802.562 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:42.562Z,1551998802.562 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:42.563Z,1551998802.563 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:42.564Z,1551998802.564 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:45.440Z,1551998805.440 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:46:45.440Z,1551998805.440 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:46:45.440Z,1551998805.440 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:46:45.440Z,1551998805.440 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:46:45.440Z,1551998805.440 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:46:45.440Z,1551998805.440 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater](INFO): Running loop #3 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater] Running Loop=3 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:46:45.441Z,1551998805.441 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:46:46.599Z,1551998806.599 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:46.599Z,1551998806.599 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:46.599Z,1551998806.599 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:46.599Z,1551998806.599 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:46.600Z,1551998806.600 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:46.605Z,1551998806.605 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:46.606Z,1551998806.606 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:47.432Z,1551998807.432 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:46:47.432Z,1551998807.432 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-07T22:46:47.432Z,1551998807.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-07T22:46:47.434Z,1551998807.434 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:46:47.434Z,1551998807.434 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-07T22:46:47.434Z,1551998807.434 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-07T22:46:47.435Z,1551998807.435 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:46:47.435Z,1551998807.435 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-03-07T22:46:47.435Z,1551998807.435 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-07T22:46:47.446Z,1551998807.446 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-07T22:46:47.446Z,1551998807.446 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-07T22:46:47.447Z,1551998807.447 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-07T22:46:47.877Z,1551998807.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-07T22:46:47.877Z,1551998807.877 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-07T22:46:47.877Z,1551998807.877 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-07T22:46:47.878Z,1551998807.878 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-07T22:46:47.878Z,1551998807.878 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-07T22:46:47.878Z,1551998807.878 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-03-07T22:46:48.248Z,1551998808.248 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-07T22:46:50.640Z,1551998810.640 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:50.640Z,1551998810.640 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:50.640Z,1551998810.640 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:50.640Z,1551998810.640 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:50.641Z,1551998810.641 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:54.682Z,1551998814.682 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:54.682Z,1551998814.682 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:54.682Z,1551998814.682 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:54.683Z,1551998814.683 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:54.683Z,1551998814.683 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:46:55.927Z,1551998815.927 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:46:55.927Z,1551998815.927 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:46:55.927Z,1551998815.927 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:46:55.928Z,1551998815.928 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:46:55.928Z,1551998815.928 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:46:55.928Z,1551998815.928 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:46:55.928Z,1551998815.928 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:46:55.928Z,1551998815.928 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:46:55.928Z,1551998815.928 [DUSBL:RequestRepeater](INFO): Running loop #4 2019-03-07T22:46:55.928Z,1551998815.928 [DUSBL:RequestRepeater] Running Loop=4 2019-03-07T22:46:55.929Z,1551998815.929 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:46:55.929Z,1551998815.929 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:46:55.929Z,1551998815.929 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:46:55.929Z,1551998815.929 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:46:55.929Z,1551998815.929 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:46:56.699Z,1551998816.699 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:56.700Z,1551998816.700 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:46:58.723Z,1551998818.723 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:46:58.723Z,1551998818.723 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:46:58.723Z,1551998818.723 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:46:58.723Z,1551998818.723 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:46:58.724Z,1551998818.724 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:02.758Z,1551998822.758 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:02.758Z,1551998822.758 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:02.759Z,1551998822.759 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:02.759Z,1551998822.759 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:02.759Z,1551998822.759 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:06.422Z,1551998826.422 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:47:06.422Z,1551998826.422 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:47:06.422Z,1551998826.422 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:47:06.423Z,1551998826.423 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:47:06.423Z,1551998826.423 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:47:06.423Z,1551998826.423 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:47:06.423Z,1551998826.423 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:47:06.423Z,1551998826.423 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:47:06.424Z,1551998826.424 [DUSBL:RequestRepeater](INFO): Running loop #5 2019-03-07T22:47:06.424Z,1551998826.424 [DUSBL:RequestRepeater] Running Loop=5 2019-03-07T22:47:06.424Z,1551998826.424 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:47:06.424Z,1551998826.424 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:47:06.424Z,1551998826.424 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:47:06.424Z,1551998826.424 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:47:06.424Z,1551998826.424 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:47:06.808Z,1551998826.808 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:06.809Z,1551998826.809 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:06.809Z,1551998826.809 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:06.809Z,1551998826.809 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:06.810Z,1551998826.810 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:06.815Z,1551998826.815 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:06.816Z,1551998826.816 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:10.845Z,1551998830.845 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:10.845Z,1551998830.845 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:10.846Z,1551998830.846 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:10.846Z,1551998830.846 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:10.847Z,1551998830.847 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:14.878Z,1551998834.878 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:14.878Z,1551998834.878 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:14.879Z,1551998834.879 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:14.879Z,1551998834.879 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:14.880Z,1551998834.880 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:16.903Z,1551998836.903 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:16.905Z,1551998836.905 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:16.951Z,1551998836.951 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:47:16.951Z,1551998836.951 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:47:16.951Z,1551998836.951 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:47:16.951Z,1551998836.951 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:47:16.951Z,1551998836.951 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater](INFO): Running loop #6 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater] Running Loop=6 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:47:16.952Z,1551998836.952 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:47:18.918Z,1551998838.918 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:18.918Z,1551998838.918 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:18.919Z,1551998838.919 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:18.919Z,1551998838.919 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:18.920Z,1551998838.920 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:22.959Z,1551998842.959 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:22.959Z,1551998842.959 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:22.959Z,1551998842.959 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:22.959Z,1551998842.959 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:22.960Z,1551998842.960 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:26.600Z,1551998846.600 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:26.601Z,1551998846.601 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:26.998Z,1551998846.998 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:26.998Z,1551998846.998 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:26.999Z,1551998846.999 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:26.999Z,1551998846.999 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:26.000Z,1551998847.000 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:27.430Z,1551998847.430 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:47:27.430Z,1551998847.430 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:47:27.430Z,1551998847.430 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:47:27.431Z,1551998847.431 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:47:27.431Z,1551998847.431 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:47:27.431Z,1551998847.431 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:47:27.431Z,1551998847.431 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:47:27.431Z,1551998847.431 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:47:27.431Z,1551998847.431 [DUSBL:RequestRepeater](INFO): Running loop #7 2019-03-07T22:47:27.432Z,1551998847.432 [DUSBL:RequestRepeater] Running Loop=7 2019-03-07T22:47:27.432Z,1551998847.432 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:47:27.432Z,1551998847.432 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:47:27.432Z,1551998847.432 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:47:27.432Z,1551998847.432 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:47:27.432Z,1551998847.432 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:47:31.042Z,1551998851.042 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:31.042Z,1551998851.042 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:31.042Z,1551998851.042 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:31.043Z,1551998851.043 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:31.043Z,1551998851.043 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:35.082Z,1551998855.082 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:35.083Z,1551998855.083 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:35.083Z,1551998855.083 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:35.083Z,1551998855.083 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:35.084Z,1551998855.084 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:36.707Z,1551998856.707 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:36.708Z,1551998856.708 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:37.943Z,1551998857.943 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:47:37.943Z,1551998857.943 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:47:37.943Z,1551998857.943 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater](INFO): Running loop #8 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater] Running Loop=8 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:47:37.944Z,1551998857.944 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:47:37.949Z,1551998857.949 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:47:37.949Z,1551998857.949 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:47:37.949Z,1551998857.949 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:47:39.118Z,1551998859.118 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:39.118Z,1551998859.118 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:39.119Z,1551998859.119 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:39.119Z,1551998859.119 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:39.119Z,1551998859.119 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:43.158Z,1551998863.158 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:43.158Z,1551998863.158 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:43.159Z,1551998863.159 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:43.159Z,1551998863.159 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:43.160Z,1551998863.160 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:46.799Z,1551998866.799 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:46.800Z,1551998866.800 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:47.198Z,1551998867.198 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:47.199Z,1551998867.199 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:47.199Z,1551998867.199 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:47.199Z,1551998867.199 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:47.200Z,1551998867.200 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:48.458Z,1551998868.458 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting. 2019-03-07T22:47:48.458Z,1551998868.458 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:47:48.458Z,1551998868.458 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:47:48.459Z,1551998868.459 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater 2019-03-07T22:47:48.459Z,1551998868.459 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:47:48.459Z,1551998868.459 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:47:48.459Z,1551998868.459 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater](INFO): Running loop #9 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater] Running Loop=9 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater:A] Running Loop=1 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater:B] Running Loop=1 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater:C.Wait] Running Loop=1 2019-03-07T22:47:48.460Z,1551998868.460 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:47:51.242Z,1551998871.242 [DUSBL_Hydroid](INFO): ****** received transponder code query ****** 2019-03-07T22:47:51.242Z,1551998871.242 [DUSBL_Hydroid](INFO): ****** received ping request ****** 2019-03-07T22:47:51.243Z,1551998871.243 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2 2019-03-07T22:47:51.243Z,1551998871.243 [DUSBL_Hydroid](DEBUG): Arming DUSBL 2019-03-07T22:47:51.244Z,1551998871.244 [DUSBL_Hydroid](INFO): ****** ping requested ****** 2019-03-07T22:47:51.764Z,1551998871.764 [CommandLine](IMPORTANT): got command stop 2019-03-07T22:47:51.764Z,1551998871.764 [CommandLine](IMPORTANT): Scheduling is paused 2019-03-07T22:47:51.764Z,1551998871.764 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2019-03-07T22:47:52.089Z,1551998872.089 [MissionManager](INFO): MissionManager is completed. 2019-03-07T22:47:52.089Z,1551998872.089 [MissionManager](INFO): Uninitializing Mission DUSBL 2019-03-07T22:47:52.089Z,1551998872.089 [DUSBL] Stopped 2019-03-07T22:47:52.089Z,1551998872.089 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL 2019-03-07T22:47:52.089Z,1551998872.089 [DUSBL:A.Pitch] Stopped 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:B.SetSpeed] Stopped 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:B.SetSpeed](DEBUG): Uninitialize. 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:C] Stopped 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:RequestRepeater] Stopped 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:RequestRepeater:A] Stopped 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:RequestRepeater:B] Stopped 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:RequestRepeater:C.Wait] Stopped 2019-03-07T22:47:52.090Z,1551998872.090 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:47:52.487Z,1551998872.487 [MissionManager](IMPORTANT): Started mission Default 2019-03-07T22:47:52.487Z,1551998872.487 [Default] Running Loop=1 2019-03-07T22:47:52.487Z,1551998872.487 [Default](DEBUG): Aggregate::initialize Default 2019-03-07T22:47:52.487Z,1551998872.487 [Default:B.GoToSurface] Running Loop=1 2019-03-07T22:47:52.487Z,1551998872.487 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-07T22:47:52.487Z,1551998872.487 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-07T22:47:52.488Z,1551998872.488 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-07T22:47:52.488Z,1551998872.488 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-07T22:47:52.488Z,1551998872.488 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-07T22:47:52.489Z,1551998872.489 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-07T22:47:52.489Z,1551998872.489 [Default:A.Wait] Running Loop=1 2019-03-07T22:47:52.489Z,1551998872.489 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-07T22:47:56.899Z,1551998876.899 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:47:56.900Z,1551998876.900 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:05.817Z,1551998885.817 [Default:A.Wait](INFO): Done Waiting. 2019-03-07T22:48:05.817Z,1551998885.817 [Default:A.Wait] Stopped 2019-03-07T22:48:05.817Z,1551998885.817 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-07T22:48:06.218Z,1551998886.218 [Default:CheckIn] Running Loop=1 2019-03-07T22:48:06.219Z,1551998886.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-07T22:48:06.219Z,1551998886.219 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-07T22:48:06.592Z,1551998886.592 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:06.594Z,1551998886.594 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:16.695Z,1551998896.695 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:16.696Z,1551998896.696 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:26.795Z,1551998906.795 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:26.796Z,1551998906.796 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:27.599Z,1551998907.599 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-03-07T22:48:28.425Z,1551998908.425 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:30.849Z,1551998910.849 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:33.677Z,1551998913.677 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:36.895Z,1551998916.895 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:36.896Z,1551998916.896 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:36.909Z,1551998916.909 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:39.741Z,1551998919.741 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:42.969Z,1551998922.969 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:45.797Z,1551998925.797 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:46.588Z,1551998926.588 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:46.590Z,1551998926.590 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:48.749Z,1551998928.749 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:48:48.749Z,1551998928.749 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-03-07T22:48:48.749Z,1551998928.749 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-07T22:48:48.751Z,1551998928.751 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:48:48.751Z,1551998928.751 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-03-07T22:48:48.751Z,1551998928.751 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-07T22:48:48.752Z,1551998928.752 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-07T22:48:48.752Z,1551998928.752 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-03-07T22:48:48.752Z,1551998928.752 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-07T22:48:48.776Z,1551998928.776 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-07T22:48:48.776Z,1551998928.776 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-07T22:48:48.776Z,1551998928.776 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-07T22:48:49.041Z,1551998929.041 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:49.104Z,1551998929.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-07T22:48:49.104Z,1551998929.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-03-07T22:48:49.109Z,1551998929.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-07T22:48:49.109Z,1551998929.109 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-03-07T22:48:49.109Z,1551998929.109 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-07T22:48:49.110Z,1551998929.110 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2019-03-07T22:48:49.464Z,1551998929.464 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-07T22:48:51.861Z,1551998931.861 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:54.685Z,1551998934.685 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:48:56.687Z,1551998936.687 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:56.688Z,1551998936.688 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:48:57.917Z,1551998937.917 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:00.745Z,1551998940.745 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:03.977Z,1551998943.977 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:06.791Z,1551998946.791 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:06.792Z,1551998946.792 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:06.805Z,1551998946.805 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:10.037Z,1551998950.037 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:12.865Z,1551998952.865 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:15.693Z,1551998955.693 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:16.887Z,1551998956.887 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:16.889Z,1551998956.889 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:18.925Z,1551998958.925 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:21.753Z,1551998961.753 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:24.981Z,1551998964.981 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:26.584Z,1551998966.584 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:26.991Z,1551998966.991 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:27.813Z,1551998967.813 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:31.045Z,1551998971.045 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:33.873Z,1551998973.873 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:36.688Z,1551998976.688 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:36.690Z,1551998976.690 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:36.701Z,1551998976.701 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:39.933Z,1551998979.933 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:42.761Z,1551998982.761 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:45.989Z,1551998985.989 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:46.783Z,1551998986.783 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:46.784Z,1551998986.784 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:48.821Z,1551998988.821 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:52.053Z,1551998992.053 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:54.885Z,1551998994.885 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:56.887Z,1551998996.887 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:56.888Z,1551998996.888 [Micromodem](IMPORTANT): RX NMEA 2019-03-07T22:49:57.721Z,1551998997.721 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:49:58.766Z,1551998998.766 [CommandLine](IMPORTANT): got command quit 2019-03-07T22:49:59.769Z,1551998999.769 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:49:59.769Z,1551998999.769 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:49:59.793Z,1551998999.793 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-03-07T22:49:59.793Z,1551998999.793 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:49:59.794Z,1551998999.794 [CommandLine](INFO): Join timeout helper Thread ID is 7575 2019-03-07T22:49:59.801Z,1551998999.801 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-03-07T22:49:59.801Z,1551998999.801 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:49:59.802Z,1551998999.802 [NavChartDb](INFO): Join timeout helper Thread ID is 7576 2019-03-07T22:49:59.981Z,1551998999.981 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:49:59.981Z,1551998999.981 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.001Z,1551999000.001 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2019-03-07T22:50:00.001Z,1551999000.001 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.001Z,1551999000.001 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7577 2019-03-07T22:50:00.297Z,1551999000.297 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:50:00.297Z,1551999000.297 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-03-07T22:50:00.298Z,1551999000.298 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.313Z,1551999000.313 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-03-07T22:50:00.313Z,1551999000.313 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.313Z,1551999000.313 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7578 2019-03-07T22:50:00.333Z,1551999000.333 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:50:00.333Z,1551999000.333 [CTD_NeilBrown](INFO): Powering down 2019-03-07T22:50:00.345Z,1551999000.345 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.361Z,1551999000.361 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-03-07T22:50:00.361Z,1551999000.361 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.361Z,1551999000.361 [Radio_Surface](INFO): Join timeout helper Thread ID is 7579 2019-03-07T22:50:00.713Z,1551999000.713 [Radio_Surface](INFO): Powering down 2019-03-07T22:50:00.714Z,1551999000.714 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:50:00.714Z,1551999000.714 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.730Z,1551999000.730 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-03-07T22:50:00.730Z,1551999000.730 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:00.730Z,1551999000.730 [DataOverHttps](INFO): Join timeout helper Thread ID is 7580 2019-03-07T22:50:00.937Z,1551999000.937 [NAL9602](DEBUG): Fix Requested 2019-03-07T22:50:01.146Z,1551999001.146 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:50:01.149Z,1551999001.149 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.166Z,1551999001.166 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-03-07T22:50:01.166Z,1551999001.166 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.166Z,1551999001.166 [logger](INFO): Join timeout helper Thread ID is 7581 2019-03-07T22:50:01.265Z,1551999001.265 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:50:01.265Z,1551999001.265 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.285Z,1551999001.285 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-03-07T22:50:01.286Z,1551999001.286 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.286Z,1551999001.286 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-03-07T22:50:01.286Z,1551999001.286 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.286Z,1551999001.286 [controlThread](INFO): Join timeout helper Thread ID is 7582 2019-03-07T22:50:01.317Z,1551999001.317 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-07T22:50:01.317Z,1551999001.317 [controlThread](DEBUG): Uninitializing ControlThread 2019-03-07T22:50:01.318Z,1551999001.318 [DUSBL_Hydroid](INFO): Powering down 2019-03-07T22:50:01.389Z,1551999001.389 [Micromodem](INFO): uninitialize 2019-03-07T22:50:01.389Z,1551999001.389 [Micromodem](INFO): Powering down 2019-03-07T22:50:01.485Z,1551999001.485 [NAL9602](INFO): Powering down 2019-03-07T22:50:01.557Z,1551999001.557 [RDI_Pathfinder](INFO): Powering down 2019-03-07T22:50:01.558Z,1551999001.558 [RDI_PathfinderUp](INFO): Powering down 2019-03-07T22:50:01.559Z,1551999001.559 [Aanderaa_O2](INFO): Powering down 2019-03-07T22:50:01.560Z,1551999001.560 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-03-07T22:50:01.561Z,1551999001.561 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-03-07T22:50:01.562Z,1551999001.562 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-03-07T22:50:01.563Z,1551999001.563 [MissionManager](INFO): Uninitializing Mission Default 2019-03-07T22:50:01.563Z,1551999001.563 [Default] Stopped 2019-03-07T22:50:01.563Z,1551999001.563 [Default](DEBUG): Aggregate::uninitialize Default 2019-03-07T22:50:01.563Z,1551999001.563 [Default:B.GoToSurface] Stopped 2019-03-07T22:50:01.563Z,1551999001.563 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-07T22:50:01.563Z,1551999001.563 [Default:CheckIn] Stopped 2019-03-07T22:50:01.563Z,1551999001.563 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-07T22:50:01.563Z,1551999001.563 [Default:CheckIn:Read_GPS] Stopped 2019-03-07T22:50:01.566Z,1551999001.566 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-03-07T22:50:01.566Z,1551999001.566 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-03-07T22:50:01.566Z,1551999001.566 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-03-07T22:50:01.567Z,1551999001.567 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-03-07T22:50:01.567Z,1551999001.567 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-03-07T22:50:01.567Z,1551999001.567 [BuoyancyServo](INFO): Powering down 2019-03-07T22:50:01.581Z,1551999001.581 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-07T22:50:01.581Z,1551999001.581 [ElevatorServo](INFO): Powering down 2019-03-07T22:50:01.582Z,1551999001.582 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-07T22:50:01.582Z,1551999001.582 [MassServo](INFO): Powering down 2019-03-07T22:50:01.583Z,1551999001.583 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-07T22:50:01.583Z,1551999001.583 [RudderServo](INFO): Powering down 2019-03-07T22:50:01.584Z,1551999001.584 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-03-07T22:50:01.584Z,1551999001.584 [ThrusterServo](INFO): Powering down 2019-03-07T22:50:01.584Z,1551999001.584 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-03-07T22:50:01.585Z,1551999001.585 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-03-07T22:50:01.585Z,1551999001.585 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-03-07T22:50:01.585Z,1551999001.585 [CBIT](DEBUG): Powering off loads. 2019-03-07T22:50:01.597Z,1551999001.597 [CBIT](DEBUG): Disabling WDT. 2019-03-07T22:50:01.609Z,1551999001.609 [CBIT](DEBUG): Opening all GF detection circuits. 2019-03-07T22:50:01.610Z,1551999001.610 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.674Z,1551999001.674 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.683Z,1551999001.683 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.722Z,1551999001.722 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.724Z,1551999001.724 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.777Z,1551999001.777 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-07T22:50:01.833Z,1551999001.833 [logger ThreadHandler](INFO): Thread cancelled.