2018-11-06T20:12:03.372Z,1541535123.372 [Supervisor](DEBUG): Initializing supervisor.
2018-11-06T20:12:03.375Z,1541535123.375 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-06T20:12:03.376Z,1541535123.376 [SyncHandler](INFO): Protected caller Thread ID is 2371
2018-11-06T20:12:03.376Z,1541535123.376 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-06T20:12:03.377Z,1541535123.377 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-06T20:12:03.377Z,1541535123.377 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2372
2018-11-06T20:12:03.380Z,1541535123.380 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-06T20:12:03.392Z,1541535123.392 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-06T20:12:03.393Z,1541535123.393 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-06T20:12:03.394Z,1541535123.394 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2373
2018-11-06T20:12:03.395Z,1541535123.395 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-06T20:12:03.396Z,1541535123.396 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-06T20:12:03.396Z,1541535123.396 [logger ThreadHandler](INFO): Protected caller Thread ID is 2374
2018-11-06T20:12:03.398Z,1541535123.398 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-06T20:12:03.398Z,1541535123.398 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-06T20:12:03.400Z,1541535123.400 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-06T20:12:03.498Z,1541535123.498 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-06T20:12:03.499Z,1541535123.499 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-06T20:12:03.600Z,1541535123.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-06T20:12:03.601Z,1541535123.601 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-06T20:12:03.803Z,1541535123.803 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-06T20:12:03.803Z,1541535123.803 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-06T20:12:04.141Z,1541535124.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-06T20:12:04.141Z,1541535124.141 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-06T20:12:04.487Z,1541535124.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-06T20:12:04.487Z,1541535124.487 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-06T20:12:04.948Z,1541535124.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-06T20:12:04.948Z,1541535124.948 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-06T20:12:05.414Z,1541535125.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-06T20:12:05.415Z,1541535125.415 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-06T20:12:06.151Z,1541535126.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-06T20:12:06.151Z,1541535126.151 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-06T20:12:06.233Z,1541535126.233 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-06T20:12:06.559Z,1541535126.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-06T20:12:06.560Z,1541535126.560 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-06T20:12:06.710Z,1541535126.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-06T20:12:06.711Z,1541535126.711 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-06T20:12:06.927Z,1541535126.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-06T20:12:06.927Z,1541535126.927 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-06T20:12:07.135Z,1541535127.135 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-06T20:12:07.136Z,1541535127.136 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-06T20:12:07.235Z,1541535127.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-06T20:12:07.235Z,1541535127.235 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-06T20:12:07.382Z,1541535127.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-06T20:12:07.384Z,1541535127.384 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-11-06T20:12:07.385Z,1541535127.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-11-06T20:12:07.614Z,1541535127.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-06T20:12:07.614Z,1541535127.614 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-11-06T20:12:07.721Z,1541535127.721 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-11-06T20:12:07.872Z,1541535127.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-11-06T20:12:07.966Z,1541535127.966 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-11-06T20:12:08.052Z,1541535128.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-11-06T20:12:08.209Z,1541535128.209 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-11-06T20:12:08.408Z,1541535128.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-11-06T20:12:08.507Z,1541535128.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-11-06T20:12:08.603Z,1541535128.603 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-11-06T20:12:08.703Z,1541535128.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-11-06T20:12:08.788Z,1541535128.788 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-11-06T20:12:08.788Z,1541535128.788 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-06T20:12:08.791Z,1541535128.791 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-06T20:12:08.892Z,1541535128.892 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-06T20:12:08.893Z,1541535128.893 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-06T20:12:08.906Z,1541535128.906 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-06T20:12:08.906Z,1541535128.906 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-06T20:12:08.948Z,1541535128.948 [DepthRateCalculator] Loaded
2018-11-06T20:12:08.948Z,1541535128.948 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-06T20:12:08.954Z,1541535128.954 [PitchRateCalculator] Loaded
2018-11-06T20:12:08.954Z,1541535128.954 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-06T20:12:08.970Z,1541535128.970 [SpeedCalculator] Loaded
2018-11-06T20:12:08.970Z,1541535128.970 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-06T20:12:08.991Z,1541535128.991 [TempGradientCalculator] Loaded
2018-11-06T20:12:08.991Z,1541535128.991 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-06T20:12:08.997Z,1541535128.997 [YawRateCalculator] Loaded
2018-11-06T20:12:08.997Z,1541535128.997 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-06T20:12:09.037Z,1541535129.037 [ElevatorOffsetCalculator] Loaded
2018-11-06T20:12:09.037Z,1541535129.037 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-06T20:12:09.038Z,1541535129.038 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-06T20:12:09.038Z,1541535129.038 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-06T20:12:09.063Z,1541535129.063 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-06T20:12:09.064Z,1541535129.064 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-06T20:12:09.340Z,1541535129.340 [AcousticModem_Benthos_ATM900] Loaded
2018-11-06T20:12:09.341Z,1541535129.341 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-11-06T20:12:09.413Z,1541535129.413 [DataOverHttps] Loaded
2018-11-06T20:12:09.413Z,1541535129.413 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-06T20:12:09.427Z,1541535129.427 [Depth_Keller] Loaded
2018-11-06T20:12:09.427Z,1541535129.427 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-06T20:12:09.432Z,1541535129.432 [DropWeight] Loaded
2018-11-06T20:12:09.432Z,1541535129.432 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-06T20:12:09.529Z,1541535129.529 [NAL9602] Loaded
2018-11-06T20:12:09.530Z,1541535129.530 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-06T20:12:09.545Z,1541535129.545 [Onboard] Loaded
2018-11-06T20:12:09.546Z,1541535129.546 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-06T20:12:09.556Z,1541535129.556 [Radio_Surface] Loaded
2018-11-06T20:12:09.556Z,1541535129.556 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-06T20:12:09.557Z,1541535129.557 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0
2018-11-06T20:12:09.558Z,1541535129.558 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2453
2018-11-06T20:12:09.604Z,1541535129.604 [PNI_TCM] Loaded
2018-11-06T20:12:09.605Z,1541535129.605 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-06T20:12:09.667Z,1541535129.667 [Rowe_600LCM] Loaded
2018-11-06T20:12:09.667Z,1541535129.667 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-11-06T20:12:09.668Z,1541535129.668 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0
2018-11-06T20:12:09.668Z,1541535129.668 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 2454
2018-11-06T20:12:11.411Z,1541535131.411 [BPC1] Loaded
2018-11-06T20:12:11.411Z,1541535131.411 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-06T20:12:11.412Z,1541535131.412 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-06T20:12:11.412Z,1541535131.412 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-06T20:12:11.920Z,1541535131.920 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-06T20:12:11.990Z,1541535131.990 [SBIT] Loaded
2018-11-06T20:12:12.002Z,1541535132.002 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-06T20:12:12.003Z,1541535132.003 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-06T20:12:12.034Z,1541535132.034 [IBIT] Loaded
2018-11-06T20:12:12.034Z,1541535132.034 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-06T20:12:12.038Z,1541535132.038 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-06T20:12:12.381Z,1541535132.381 [CBIT] Loaded
2018-11-06T20:12:12.381Z,1541535132.381 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-06T20:12:12.382Z,1541535132.382 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-06T20:12:12.382Z,1541535132.382 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-06T20:12:12.542Z,1541535132.542 [Aanderaa_O2] Loaded
2018-11-06T20:12:12.543Z,1541535132.543 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-06T20:12:12.600Z,1541535132.600 [CTD_Seabird] Loaded
2018-11-06T20:12:12.600Z,1541535132.600 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-11-06T20:12:12.601Z,1541535132.601 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0
2018-11-06T20:12:12.601Z,1541535132.601 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2455
2018-11-06T20:12:12.619Z,1541535132.619 [ESPComponent] Loaded
2018-11-06T20:12:12.619Z,1541535132.619 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-11-06T20:12:12.633Z,1541535132.633 [PAR_Licor] Loaded
2018-11-06T20:12:12.633Z,1541535132.633 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-11-06T20:12:12.682Z,1541535132.682 [WetLabsBB2FL] Loaded
2018-11-06T20:12:12.682Z,1541535132.682 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-06T20:12:12.683Z,1541535132.683 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2018-11-06T20:12:12.683Z,1541535132.683 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2456
2018-11-06T20:12:12.684Z,1541535132.684 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-06T20:12:12.685Z,1541535132.685 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-06T20:12:12.927Z,1541535132.927 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-06T20:12:12.927Z,1541535132.927 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-06T20:12:12.995Z,1541535132.995 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-06T20:12:13.091Z,1541535133.091 [VerticalControl] Loaded
2018-11-06T20:12:13.091Z,1541535133.091 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-06T20:12:13.092Z,1541535133.092 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-06T20:12:13.152Z,1541535133.152 [HorizontalControl] Loaded
2018-11-06T20:12:13.153Z,1541535133.153 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-06T20:12:13.153Z,1541535133.153 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-06T20:12:13.158Z,1541535133.158 [SpeedControl] Loaded
2018-11-06T20:12:13.159Z,1541535133.159 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-06T20:12:13.159Z,1541535133.159 [LoopControl](DEBUG): Construct LoopControl.
2018-11-06T20:12:13.160Z,1541535133.160 [LoopControl] Loaded
2018-11-06T20:12:13.160Z,1541535133.160 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-06T20:12:13.161Z,1541535133.161 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-06T20:12:13.161Z,1541535133.161 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-06T20:12:13.225Z,1541535133.225 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-06T20:12:13.226Z,1541535133.226 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-06T20:12:13.324Z,1541535133.324 [BuoyancyServo] Loaded
2018-11-06T20:12:13.324Z,1541535133.324 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-06T20:12:13.336Z,1541535133.336 [ElevatorServo] Loaded
2018-11-06T20:12:13.336Z,1541535133.336 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-06T20:12:13.347Z,1541535133.347 [MassServo] Loaded
2018-11-06T20:12:13.348Z,1541535133.348 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-06T20:12:13.359Z,1541535133.359 [RudderServo] Loaded
2018-11-06T20:12:13.359Z,1541535133.359 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-06T20:12:13.370Z,1541535133.370 [ThrusterServo] Loaded
2018-11-06T20:12:13.371Z,1541535133.371 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-06T20:12:13.371Z,1541535133.371 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-06T20:12:13.372Z,1541535133.372 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-06T20:12:13.462Z,1541535133.462 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-06T20:12:13.462Z,1541535133.462 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-06T20:12:13.478Z,1541535133.478 [NavChart] Loaded
2018-11-06T20:12:13.478Z,1541535133.478 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-06T20:12:13.483Z,1541535133.483 [UniversalFixResidualReporter] Loaded
2018-11-06T20:12:13.483Z,1541535133.483 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-06T20:12:13.483Z,1541535133.483 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-06T20:12:13.487Z,1541535133.487 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-06T20:12:13.488Z,1541535133.488 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-06T20:12:13.494Z,1541535133.494 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-06T20:12:13.495Z,1541535133.495 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-11-06T20:12:13.496Z,1541535133.496 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2457
2018-11-06T20:12:13.500Z,1541535133.500 [Supervisor](INFO): Main Thread ID is 795
2018-11-06T20:12:13.501Z,1541535133.501 [Supervisor](DEBUG): Running supervisor.
2018-11-06T20:12:13.501Z,1541535133.501 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2458
2018-11-06T20:12:13.504Z,1541535133.504 [controlThread ThreadHandler](INFO): Handler Thread ID is 2459
2018-11-06T20:12:13.504Z,1541535133.504 [controlThread](DEBUG): Initializing ControlThread
2018-11-06T20:12:13.505Z,1541535133.505 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T20:12:13.505Z,1541535133.505 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-06T20:12:13.506Z,1541535133.506 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-06T20:12:13.506Z,1541535133.506 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-06T20:12:13.507Z,1541535133.507 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-06T20:12:13.507Z,1541535133.507 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-06T20:12:13.513Z,1541535133.513 [SBIT](INFO): Initialize SBIT Component.
2018-11-06T20:12:13.513Z,1541535133.513 [SBIT](IMPORTANT): git: 2018-10-09-31-g8f1ca26
2018-11-06T20:12:13.513Z,1541535133.513 [SBIT](INFO): git hash: 8f1ca2616133f591d84f59109522710795260801
2018-11-06T20:12:13.514Z,1541535133.514 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-06T20:12:13.514Z,1541535133.514 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-06T20:12:13.515Z,1541535133.515 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-11-06T20:12:13.516Z,1541535133.516 [IBIT](INFO): Initialize IBIT Component.
2018-11-06T20:12:13.517Z,1541535133.517 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-06T20:12:13.517Z,1541535133.517 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-06T20:12:13.518Z,1541535133.518 [logger ThreadHandler](INFO): Handler Thread ID is 2460
2018-11-06T20:12:13.535Z,1541535133.535 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2461
2018-11-06T20:12:13.541Z,1541535133.541 [Radio_Surface](INFO): Powering up
2018-11-06T20:12:13.546Z,1541535133.546 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 2462
2018-11-06T20:12:13.547Z,1541535133.547 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:12:13.548Z,1541535133.548 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:12:13.557Z,1541535133.557 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:12:13.557Z,1541535133.557 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:12:13.563Z,1541535133.563 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2463
2018-11-06T20:12:13.563Z,1541535133.563 [CTD_Seabird](INFO): Initializing
2018-11-06T20:12:13.564Z,1541535133.564 [CTD_Seabird](INFO): Checking LCM
2018-11-06T20:12:13.564Z,1541535133.564 [CTD_Seabird](INFO): LCM OK
2018-11-06T20:12:13.564Z,1541535133.564 [CTD_Seabird](INFO): Powering up
2018-11-06T20:12:13.571Z,1541535133.571 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2464
2018-11-06T20:12:13.572Z,1541535133.572 [WetLabsBB2FL](INFO): Powering down
2018-11-06T20:12:13.599Z,1541535133.599 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2465
2018-11-06T20:12:13.602Z,1541535133.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-06T20:12:13.603Z,1541535133.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-06T20:12:13.603Z,1541535133.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-06T20:12:13.603Z,1541535133.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-06T20:12:13.603Z,1541535133.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-06T20:12:13.603Z,1541535133.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-06T20:12:13.604Z,1541535133.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-06T20:12:13.604Z,1541535133.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-06T20:12:13.604Z,1541535133.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-06T20:12:13.604Z,1541535133.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-06T20:12:13.604Z,1541535133.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-06T20:12:13.605Z,1541535133.605 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-06T20:12:13.605Z,1541535133.605 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-06T20:12:13.605Z,1541535133.605 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-06T20:12:13.605Z,1541535133.605 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-06T20:12:13.605Z,1541535133.605 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-06T20:12:13.626Z,1541535133.626 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-06T20:12:13.628Z,1541535133.628 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-06T20:12:13.629Z,1541535133.629 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-06T20:12:13.629Z,1541535133.629 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-06T20:12:13.631Z,1541535133.631 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:12:13.632Z,1541535133.632 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:12:13.632Z,1541535133.632 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:12:13.633Z,1541535133.633 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-06T20:12:13.633Z,1541535133.633 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-06T20:12:13.634Z,1541535133.634 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-06T20:12:13.663Z,1541535133.663 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T20:12:13.694Z,1541535133.694 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-11-06T20:12:13.695Z,1541535133.695 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-06T20:12:13.790Z,1541535133.790 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-06T20:12:13.791Z,1541535133.791 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-06T20:12:13.793Z,1541535133.793 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T20:12:13.816Z,1541535133.816 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-06T20:12:13.835Z,1541535133.835 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-06T20:12:13.840Z,1541535133.840 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-06T20:12:13.860Z,1541535133.860 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-11-06T20:12:13.865Z,1541535133.865 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-06T20:12:13.875Z,1541535133.875 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-11-06T20:12:13.876Z,1541535133.876 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-11-06T20:12:13.901Z,1541535133.901 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-06T20:12:13.910Z,1541535133.910 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2018-11-06T20:12:14.100Z,1541535134.100 [ESPComponent](INFO): powering down ESP
2018-11-06T20:12:14.473Z,1541535134.473 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-06T20:12:14.662Z,1541535134.662 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:12:14.670Z,1541535134.670 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T20:12:14.689Z,1541535134.689 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:12:14.690Z,1541535134.690 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-06T20:12:14.712Z,1541535134.712 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:12:14.718Z,1541535134.718 [MassServo](DEBUG): Initializing MassServo.
2018-11-06T20:12:14.725Z,1541535134.725 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:12:14.730Z,1541535134.730 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-06T20:12:14.736Z,1541535134.736 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:12:14.742Z,1541535134.742 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-06T20:12:15.020Z,1541535135.020 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-06T20:12:15.020Z,1541535135.020 [DropWeight] Hardware Fault, FailCount= 1
2018-11-06T20:12:15.020Z,1541535135.020 [DropWeight](ERROR): Hardware Fault
2018-11-06T20:12:15.098Z,1541535135.098 [CommandLine](FAULT): Scheduling is paused
2018-11-06T20:12:15.099Z,1541535135.099 [CBIT](INFO): Critical error at 20181106T201215
2018-11-06T20:12:15.099Z,1541535135.099 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-06T20:12:15.101Z,1541535135.101 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-06T20:12:15.118Z,1541535135.118 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-06T20:12:15.622Z,1541535135.622 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-06T20:12:15.622Z,1541535135.622 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-06T20:12:15.622Z,1541535135.622 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-06T20:12:15.622Z,1541535135.622 [BuoyancyServo](ERROR): Communications Fault
2018-11-06T20:12:15.828Z,1541535135.828 [CBIT](INFO): Critical error at 20181106T201215
2018-11-06T20:12:15.830Z,1541535135.830 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-06T20:12:15.892Z,1541535135.892 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T20:12:15.892Z,1541535135.892 [BuoyancyServo](INFO): Powering down
2018-11-06T20:12:17.808Z,1541535137.808 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:12:17.967Z,1541535137.967 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:12:17.991Z,1541535137.991 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:12:17.991Z,1541535137.991 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:12:17.992Z,1541535137.992 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:12:18.028Z,1541535138.028 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-06T20:12:18.028Z,1541535138.028 [BuoyancyServo] No Fault, FailCount= 1
2018-11-06T20:12:18.304Z,1541535138.304 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:12:18.304Z,1541535138.304 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T20:12:19.119Z,1541535139.119 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T20:12:19.293Z,1541535139.293 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-11-06T20:12:19.295Z,1541535139.295 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-11-06T20:12:19.638Z,1541535139.638 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T20:12:19.639Z,1541535139.639 [CTD_Seabird](INFO): Powering down
2018-11-06T20:12:21.439Z,1541535141.439 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-11-06T20:12:21.460Z,1541535141.460 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:12:21.460Z,1541535141.460 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T20:12:21.460Z,1541535141.460 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:12:21.607Z,1541535141.607 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:12:21.860Z,1541535141.860 [PNI_TCM](INFO): Powering down
2018-11-06T20:12:22.640Z,1541535142.640 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-11-06T20:12:22.641Z,1541535142.641 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-11-06T20:12:22.641Z,1541535142.641 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-11-06T20:12:22.644Z,1541535142.644 [AcousticModem_Benthos_ATM900](DEBUG): Oct 25 2018 20:54:18 
2018-11-06T20:12:23.040Z,1541535143.040 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-11-06T20:12:23.143Z,1541535143.143 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:12:23.143Z,1541535143.143 [PNI_TCM] No Fault, FailCount= 1
2018-11-06T20:12:23.555Z,1541535143.555 [CBIT](CRITICAL): Environmental Failure. Press:14.737346 PSI. Humidity:36%. Temp:26 C. ABORTING MISSION
2018-11-06T20:12:23.840Z,1541535143.840 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-11-06T20:12:23.921Z,1541535143.921 [CBIT](INFO): Critical error at 20181106T201223
2018-11-06T20:12:24.239Z,1541535144.239 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-11-06T20:12:24.240Z,1541535144.240 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-11-06T20:12:24.240Z,1541535144.240 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-11-06T20:12:24.271Z,1541535144.271 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:12:24.271Z,1541535144.271 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:12:24.271Z,1541535144.271 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:12:24.328Z,1541535144.328 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:12:24.639Z,1541535144.639 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-11-06T20:12:24.678Z,1541535144.678 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:12:25.039Z,1541535145.039 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-11-06T20:12:25.040Z,1541535145.040 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-11-06T20:12:25.440Z,1541535145.440 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-11-06T20:12:25.603Z,1541535145.603 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:12:25.603Z,1541535145.603 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:12:30.660Z,1541535150.660 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:12:30.660Z,1541535150.660 [PNI_TCM] Data Fault, FailCount= 2
2018-11-06T20:12:30.660Z,1541535150.660 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:12:30.704Z,1541535150.704 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:12:31.059Z,1541535151.059 [PNI_TCM](INFO): Powering down
2018-11-06T20:12:32.309Z,1541535152.309 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:12:32.310Z,1541535152.310 [PNI_TCM] No Fault, FailCount= 2
2018-11-06T20:12:36.261Z,1541535156.261 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:12:36.261Z,1541535156.261 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:12:36.261Z,1541535156.261 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:12:36.326Z,1541535156.326 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:12:36.661Z,1541535156.661 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:12:37.531Z,1541535157.531 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:12:37.531Z,1541535157.531 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:12:37.923Z,1541535157.923 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-06T20:12:37.936Z,1541535157.936 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-06T20:12:39.848Z,1541535159.848 [NAL9602](INFO): Powering up NAL9602
2018-11-06T20:12:39.865Z,1541535159.865 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:12:39.866Z,1541535159.866 [PNI_TCM] Data Fault, FailCount= 3
2018-11-06T20:12:39.866Z,1541535159.866 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:12:40.352Z,1541535160.352 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:12:40.396Z,1541535160.396 [PNI_TCM](INFO): Powering down
2018-11-06T20:12:41.732Z,1541535161.732 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:12:41.732Z,1541535161.732 [PNI_TCM] No Fault, FailCount= 3
2018-11-06T20:12:42.443Z,1541535162.443 [CommandLine](IMPORTANT): got command failComponent
2018-11-06T20:12:42.444Z,1541535162.444 [CommandLine](IMPORTANT): Failed components:
2018-11-06T20:12:42.444Z,1541535162.444 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-11-06T20:12:47.987Z,1541535167.987 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:12:47.987Z,1541535167.987 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:12:47.987Z,1541535167.987 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:12:48.060Z,1541535168.060 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:12:48.060Z,1541535168.060 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:12:48.506Z,1541535168.506 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:12:48.871Z,1541535168.871 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007785
CHAN A1 (24V): -0.010663
CHAN A2 (12V): -0.005519
CHAN A3 (5V): -0.002758
CHAN B0 (3.3V): -0.001710
CHAN B1 (3.15aV): -0.000976
CHAN B2 (3.15bV): -0.001096
CHAN B3 (GND): 0.000067
OPEN: 0.004475
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-06T20:12:49.186Z,1541535169.186 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:12:49.187Z,1541535169.187 [PNI_TCM] Data Fault, FailCount= 4
2018-11-06T20:12:49.187Z,1541535169.187 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:12:49.252Z,1541535169.252 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:12:49.580Z,1541535169.580 [PNI_TCM](INFO): Powering down
2018-11-06T20:12:50.763Z,1541535170.763 [NAL9602](INFO): NAL9602 initialized
2018-11-06T20:12:50.876Z,1541535170.876 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:12:50.876Z,1541535170.876 [PNI_TCM] No Fault, FailCount= 4
2018-11-06T20:12:58.576Z,1541535178.576 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:12:58.576Z,1541535178.576 [PNI_TCM] Data Fault, FailCount= 5
2018-11-06T20:12:58.576Z,1541535178.576 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:12:58.771Z,1541535178.771 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:12:58.772Z,1541535178.772 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-06T20:12:58.993Z,1541535178.993 [PNI_TCM](INFO): Powering down
2018-11-06T20:12:59.385Z,1541535179.385 [CBIT](INFO): Critical error at 20181106T201258
2018-11-06T20:13:31.991Z,1541535211.991 [SBIT](IMPORTANT): SBIT PASSED
2018-11-06T20:13:32.050Z,1541535212.050 [CommandLine](IMPORTANT): got command configSet list
2018-11-06T20:13:32.051Z,1541535212.051 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-06T20:13:32.051Z,1541535212.051 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2018-11-06T20:13:32.426Z,1541535212.426 [MissionManager](IMPORTANT): Started mission Startup
2018-11-06T20:13:32.426Z,1541535212.426 [Startup] Running Loop=1
2018-11-06T20:13:32.427Z,1541535212.427 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-06T20:13:32.427Z,1541535212.427 [Startup:A.GoToSurface] Running Loop=1
2018-11-06T20:13:32.427Z,1541535212.427 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T20:13:32.427Z,1541535212.427 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T20:13:32.428Z,1541535212.428 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T20:13:32.428Z,1541535212.428 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T20:13:32.429Z,1541535212.429 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T20:13:32.429Z,1541535212.429 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T20:13:32.463Z,1541535212.463 [Startup:StartupSatComms] Running Loop=1
2018-11-06T20:13:32.463Z,1541535212.463 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-06T20:13:32.464Z,1541535212.464 [Startup:StartupSatComms:A] Running Loop=1
2018-11-06T20:13:32.777Z,1541535212.777 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-06T20:14:13.567Z,1541535253.567 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:14:13.567Z,1541535253.567 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-11-06T20:14:13.567Z,1541535253.567 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:14:13.589Z,1541535253.589 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:14:13.970Z,1541535253.970 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:14:14.047Z,1541535254.047 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:14:14.047Z,1541535254.047 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-06T20:14:14.047Z,1541535254.047 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:14:14.103Z,1541535254.103 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:14:14.156Z,1541535254.156 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:14:14.392Z,1541535254.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:14:14.392Z,1541535254.392 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-06T20:14:14.781Z,1541535254.781 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:14:14.781Z,1541535254.781 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:14:14.782Z,1541535254.782 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:14:15.640Z,1541535255.640 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:14:15.640Z,1541535255.640 [Rowe_600LCM] No Fault, FailCount= 1
2018-11-06T20:14:15.770Z,1541535255.770 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:14:15.771Z,1541535255.771 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:14:15.771Z,1541535255.771 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:14:15.771Z,1541535255.771 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:14:19.893Z,1541535259.893 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:14:20.007Z,1541535260.007 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:14:20.008Z,1541535260.008 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:14:20.008Z,1541535260.008 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:14:20.009Z,1541535260.009 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:14:32.797Z,1541535272.797 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-06T20:13:32.5Z
2018-11-06T20:14:32.797Z,1541535272.797 [Startup:StartupSatComms:A] Stopped
2018-11-06T20:14:32.797Z,1541535272.797 [Startup:StartupSatComms:B] Running Loop=1
2018-11-06T20:14:33.192Z,1541535273.192 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T20:15:18.323Z,1541535318.323 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:15:18.323Z,1541535318.323 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:15:19.136Z,1541535319.136 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003879
2018-11-06T20:15:24.129Z,1541535324.129 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181106T195218/Courier0004.lzma
2018-11-06T20:15:24.929Z,1541535324.929 [DataOverHttps](INFO): Moved sent file to Logs/20181106T195218/Courier0004.lzma.bak
2018-11-06T20:15:24.929Z,1541535324.929 [DataOverHttps](INFO): SBD MOMSN=8797049
2018-11-06T20:15:29.308Z,1541535329.308 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:15:29.308Z,1541535329.308 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:15:29.309Z,1541535329.309 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:15:29.327Z,1541535329.327 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:15:29.530Z,1541535329.530 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:15:30.191Z,1541535330.191 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:15:30.191Z,1541535330.191 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:15:34.081Z,1541535334.081 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-06T20:14:32.8Z
2018-11-06T20:15:34.081Z,1541535334.081 [Startup:StartupSatComms:B] Stopped
2018-11-06T20:15:34.081Z,1541535334.081 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-06T20:15:34.081Z,1541535334.081 [Startup:StartupSatComms] Stopped
2018-11-06T20:15:34.081Z,1541535334.081 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-06T20:15:34.082Z,1541535334.082 [Startup](INFO): Completed Startup
2018-11-06T20:15:34.083Z,1541535334.083 [MissionManager](INFO): Startup is completed.
2018-11-06T20:15:34.083Z,1541535334.083 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-06T20:15:34.083Z,1541535334.083 [Startup] Stopped
2018-11-06T20:15:34.083Z,1541535334.083 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-06T20:15:34.083Z,1541535334.083 [Startup:A.GoToSurface] Stopped
2018-11-06T20:15:34.083Z,1541535334.083 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T20:15:34.138Z,1541535334.138 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20181106T200005/Courier0000.lzma
2018-11-06T20:15:34.228Z,1541535334.228 [MissionManager](IMPORTANT): Started mission Default
2018-11-06T20:15:34.228Z,1541535334.228 [Default] Running Loop=1
2018-11-06T20:15:34.228Z,1541535334.228 [Default](DEBUG): Aggregate::initialize Default
2018-11-06T20:15:34.228Z,1541535334.228 [Default:B.GoToSurface] Running Loop=1
2018-11-06T20:15:34.228Z,1541535334.228 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T20:15:34.229Z,1541535334.229 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T20:15:34.229Z,1541535334.229 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T20:15:34.229Z,1541535334.229 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T20:15:34.234Z,1541535334.234 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T20:15:34.234Z,1541535334.234 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T20:15:34.235Z,1541535334.235 [Default:A.Wait] Running Loop=1
2018-11-06T20:15:34.235Z,1541535334.235 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-06T20:15:34.904Z,1541535334.904 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200005/Courier0000.lzma.bak
2018-11-06T20:15:34.904Z,1541535334.904 [DataOverHttps](INFO): SBD MOMSN=8797052
2018-11-06T20:15:40.597Z,1541535340.597 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:15:40.597Z,1541535340.597 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:15:40.597Z,1541535340.597 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:15:40.620Z,1541535340.620 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:15:41.118Z,1541535341.118 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:15:41.809Z,1541535341.809 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:15:41.809Z,1541535341.809 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:15:47.405Z,1541535347.405 [Default:A.Wait](INFO): Done Waiting.
2018-11-06T20:15:47.406Z,1541535347.406 [Default:A.Wait] Stopped
2018-11-06T20:15:47.406Z,1541535347.406 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T20:15:47.852Z,1541535347.852 [Default:CheckIn] Running Loop=1
2018-11-06T20:15:47.852Z,1541535347.852 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T20:15:47.852Z,1541535347.852 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T20:15:48.214Z,1541535348.214 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-06T20:15:52.246Z,1541535352.246 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:15:52.246Z,1541535352.246 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:15:52.246Z,1541535352.246 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:15:52.293Z,1541535352.293 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:15:52.293Z,1541535352.293 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:15:52.702Z,1541535352.702 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:16:15.004Z,1541535375.004 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:16:15.004Z,1541535375.004 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-11-06T20:16:15.004Z,1541535375.004 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:16:15.037Z,1541535375.037 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:16:15.415Z,1541535375.415 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:16:15.416Z,1541535375.416 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-11-06T20:16:15.807Z,1541535375.807 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:16:15.808Z,1541535375.808 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:16:15.808Z,1541535375.808 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:16:16.550Z,1541535376.550 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:16:16.550Z,1541535376.550 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-11-06T20:16:16.550Z,1541535376.550 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:16:16.640Z,1541535376.640 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:16:16.954Z,1541535376.954 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:16:17.134Z,1541535377.134 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:16:18.621Z,1541535378.621 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:16:18.621Z,1541535378.621 [Rowe_600LCM] No Fault, FailCount= 2
2018-11-06T20:16:18.752Z,1541535378.752 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:16:18.752Z,1541535378.752 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:16:18.753Z,1541535378.753 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:16:18.753Z,1541535378.753 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:16:22.871Z,1541535382.871 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:16:22.933Z,1541535382.933 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:16:22.935Z,1541535382.935 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:16:22.936Z,1541535382.936 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:16:22.937Z,1541535382.937 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:17:54.223Z,1541535474.223 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-11-06T20:17:58.705Z,1541535478.705 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:17:58.705Z,1541535478.705 [PNI_TCM] No Fault, FailCount= 5
2018-11-06T20:17:59.783Z,1541535479.783 [CommandLine](IMPORTANT): got command failComponent
2018-11-06T20:17:59.783Z,1541535479.783 [CommandLine](IMPORTANT): Failed components:
2018-11-06T20:17:59.783Z,1541535479.783 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2018-11-06T20:17:59.784Z,1541535479.784 [CommandLine](IMPORTANT): Aanderaa_O2: Communications Fault
2018-11-06T20:18:06.236Z,1541535486.236 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:18:06.236Z,1541535486.236 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T20:18:06.237Z,1541535486.237 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:18:06.382Z,1541535486.382 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:18:06.665Z,1541535486.665 [PNI_TCM](INFO): Powering down
2018-11-06T20:18:07.896Z,1541535487.896 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:18:07.897Z,1541535487.897 [PNI_TCM] No Fault, FailCount= 1
2018-11-06T20:18:15.453Z,1541535495.453 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:18:15.453Z,1541535495.453 [PNI_TCM] Data Fault, FailCount= 2
2018-11-06T20:18:15.453Z,1541535495.453 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:18:15.567Z,1541535495.567 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:18:15.849Z,1541535495.849 [PNI_TCM](INFO): Powering down
2018-11-06T20:18:15.979Z,1541535495.979 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:18:15.979Z,1541535495.979 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-11-06T20:18:15.979Z,1541535495.979 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:18:16.039Z,1541535496.039 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:18:16.346Z,1541535496.346 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:18:16.350Z,1541535496.350 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-11-06T20:18:16.661Z,1541535496.661 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:18:16.662Z,1541535496.662 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:18:16.662Z,1541535496.662 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:18:17.147Z,1541535497.147 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:18:17.147Z,1541535497.147 [PNI_TCM] No Fault, FailCount= 2
2018-11-06T20:18:19.186Z,1541535499.186 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:18:19.186Z,1541535499.186 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-11-06T20:18:19.186Z,1541535499.186 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:18:19.500Z,1541535499.500 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:18:19.590Z,1541535499.590 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:18:19.682Z,1541535499.682 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:18:21.099Z,1541535501.099 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:18:21.099Z,1541535501.099 [Rowe_600LCM] No Fault, FailCount= 3
2018-11-06T20:18:21.298Z,1541535501.298 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:18:21.299Z,1541535501.299 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:18:21.299Z,1541535501.299 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:18:21.299Z,1541535501.299 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:18:22.262Z,1541535502.262 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:18:22.262Z,1541535502.262 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:18:24.640Z,1541535504.640 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:18:24.640Z,1541535504.640 [PNI_TCM] Data Fault, FailCount= 3
2018-11-06T20:18:24.640Z,1541535504.640 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:18:24.691Z,1541535504.691 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:18:25.050Z,1541535505.050 [PNI_TCM](INFO): Powering down
2018-11-06T20:18:25.451Z,1541535505.451 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:18:25.589Z,1541535505.589 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:18:25.590Z,1541535505.590 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:18:25.591Z,1541535505.591 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:18:25.592Z,1541535505.592 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:18:26.272Z,1541535506.272 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:18:26.272Z,1541535506.272 [PNI_TCM] No Fault, FailCount= 3
2018-11-06T20:18:33.048Z,1541535513.048 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:18:33.048Z,1541535513.048 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:18:33.048Z,1541535513.048 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:18:33.072Z,1541535513.072 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:18:33.452Z,1541535513.452 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:18:33.848Z,1541535513.848 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:18:33.848Z,1541535513.848 [PNI_TCM] Data Fault, FailCount= 4
2018-11-06T20:18:33.848Z,1541535513.848 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:18:33.951Z,1541535513.951 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:18:34.239Z,1541535514.239 [PNI_TCM](INFO): Powering down
2018-11-06T20:18:34.360Z,1541535514.360 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:18:34.360Z,1541535514.360 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:18:35.515Z,1541535515.515 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:18:35.515Z,1541535515.515 [PNI_TCM] No Fault, FailCount= 4
2018-11-06T20:18:44.769Z,1541535524.769 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:18:44.769Z,1541535524.769 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:18:44.769Z,1541535524.769 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:18:44.824Z,1541535524.824 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:18:45.176Z,1541535525.176 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:18:45.578Z,1541535525.578 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:18:45.578Z,1541535525.578 [PNI_TCM] Data Fault, FailCount= 5
2018-11-06T20:18:45.578Z,1541535525.578 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:18:45.620Z,1541535525.620 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:18:45.620Z,1541535525.620 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-06T20:18:45.980Z,1541535525.980 [PNI_TCM](INFO): Powering down
2018-11-06T20:18:46.090Z,1541535526.090 [CBIT](INFO): Critical error at 20181106T201845
2018-11-06T20:18:46.093Z,1541535526.093 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:18:46.093Z,1541535526.093 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:18:56.379Z,1541535536.379 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:18:56.379Z,1541535536.379 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:18:56.379Z,1541535536.379 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:18:56.403Z,1541535536.403 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:18:56.404Z,1541535536.404 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:18:56.902Z,1541535536.902 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:20:16.852Z,1541535616.852 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:20:16.852Z,1541535616.852 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-11-06T20:20:16.853Z,1541535616.853 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:20:16.866Z,1541535616.866 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:20:17.267Z,1541535617.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:20:17.267Z,1541535617.267 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-11-06T20:20:17.703Z,1541535617.703 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:20:17.704Z,1541535617.704 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:20:17.704Z,1541535617.704 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:20:21.990Z,1541535621.990 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:20:21.990Z,1541535621.990 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-11-06T20:20:21.990Z,1541535621.990 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:20:22.084Z,1541535622.084 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:20:22.405Z,1541535622.405 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:20:22.580Z,1541535622.580 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:20:24.081Z,1541535624.081 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:20:24.081Z,1541535624.081 [Rowe_600LCM] No Fault, FailCount= 4
2018-11-06T20:20:24.194Z,1541535624.194 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:20:24.195Z,1541535624.195 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:20:24.195Z,1541535624.195 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:20:24.195Z,1541535624.195 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:20:28.319Z,1541535628.319 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:20:28.411Z,1541535628.411 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:20:28.412Z,1541535628.412 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:20:28.413Z,1541535628.413 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:20:28.414Z,1541535628.414 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:20:48.041Z,1541535648.041 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T20:15:47.9Z
2018-11-06T20:20:48.041Z,1541535648.041 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T20:20:48.041Z,1541535648.041 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T20:20:48.436Z,1541535648.436 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T20:20:52.869Z,1541535652.869 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T200038/Courier0000.lzma
2018-11-06T20:20:53.668Z,1541535653.668 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200038/Courier0000.lzma.bak
2018-11-06T20:20:53.669Z,1541535653.669 [DataOverHttps](INFO): SBD MOMSN=8797092
2018-11-06T20:21:03.333Z,1541535663.333 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T200103/Courier0000.lzma
2018-11-06T20:21:04.108Z,1541535664.108 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200103/Courier0000.lzma.bak
2018-11-06T20:21:04.109Z,1541535664.109 [DataOverHttps](INFO): SBD MOMSN=8797097
2018-11-06T20:21:13.338Z,1541535673.338 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T200128/Courier0000.lzma
2018-11-06T20:21:14.137Z,1541535674.137 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200128/Courier0000.lzma.bak
2018-11-06T20:21:14.140Z,1541535674.140 [DataOverHttps](INFO): SBD MOMSN=8797102
2018-11-06T20:21:23.339Z,1541535683.339 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T200152/Courier0000.lzma
2018-11-06T20:21:24.141Z,1541535684.141 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200152/Courier0000.lzma.bak
2018-11-06T20:21:24.141Z,1541535684.141 [DataOverHttps](INFO): SBD MOMSN=8797107
2018-11-06T20:21:28.547Z,1541535688.547 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:21:28.547Z,1541535688.547 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:21:33.337Z,1541535693.337 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20181106T200217/Courier0000.lzma
2018-11-06T20:21:34.108Z,1541535694.108 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200217/Courier0000.lzma.bak
2018-11-06T20:21:34.108Z,1541535694.108 [DataOverHttps](INFO): SBD MOMSN=8797112
2018-11-06T20:21:39.035Z,1541535699.035 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:21:39.035Z,1541535699.035 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:21:39.035Z,1541535699.035 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:21:39.086Z,1541535699.086 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:21:39.294Z,1541535699.294 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:21:39.975Z,1541535699.975 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:21:39.975Z,1541535699.975 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:21:43.867Z,1541535703.867 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181106T200217/Courier0004.lzma
2018-11-06T20:21:44.646Z,1541535704.646 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200217/Courier0004.lzma.bak
2018-11-06T20:21:44.662Z,1541535704.662 [DataOverHttps](INFO): SBD MOMSN=8797119
2018-11-06T20:21:50.367Z,1541535710.367 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:21:50.367Z,1541535710.367 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:21:50.367Z,1541535710.367 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:21:50.395Z,1541535710.395 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:21:50.886Z,1541535710.886 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:21:51.561Z,1541535711.561 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:21:51.561Z,1541535711.561 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:21:54.659Z,1541535714.659 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20181106T200906/Courier0000.lzma
2018-11-06T20:21:55.460Z,1541535715.460 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200906/Courier0000.lzma.bak
2018-11-06T20:21:55.460Z,1541535715.460 [DataOverHttps](INFO): SBD MOMSN=8797122
2018-11-06T20:22:04.783Z,1541535724.783 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:22:04.783Z,1541535724.783 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:22:04.783Z,1541535724.783 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:22:04.801Z,1541535724.801 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:22:04.802Z,1541535724.802 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:22:04.813Z,1541535724.813 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T200939/Courier0000.lzma
2018-11-06T20:22:05.023Z,1541535725.023 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:22:05.612Z,1541535725.612 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200939/Courier0000.lzma.bak
2018-11-06T20:22:05.612Z,1541535725.612 [DataOverHttps](INFO): SBD MOMSN=8797128
2018-11-06T20:22:14.862Z,1541535734.862 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T201004/Courier0000.lzma
2018-11-06T20:22:15.625Z,1541535735.625 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201004/Courier0000.lzma.bak
2018-11-06T20:22:15.625Z,1541535735.625 [DataOverHttps](INFO): SBD MOMSN=8797133
2018-11-06T20:22:20.125Z,1541535740.125 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:22:20.125Z,1541535740.125 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-11-06T20:22:20.125Z,1541535740.125 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:22:20.139Z,1541535740.139 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:22:20.267Z,1541535740.267 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:22:20.267Z,1541535740.267 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-11-06T20:22:20.615Z,1541535740.615 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:22:20.616Z,1541535740.616 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:22:20.616Z,1541535740.616 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:22:24.726Z,1541535744.726 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:22:24.726Z,1541535744.726 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-11-06T20:22:24.726Z,1541535744.726 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:22:25.130Z,1541535745.130 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:22:25.284Z,1541535745.284 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:22:26.278Z,1541535746.278 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:22:26.279Z,1541535746.279 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-11-06T20:22:26.293Z,1541535746.293 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T201028/Courier0000.lzma
2018-11-06T20:22:27.088Z,1541535747.088 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201028/Courier0000.lzma.bak
2018-11-06T20:22:27.089Z,1541535747.089 [DataOverHttps](INFO): SBD MOMSN=8797138
2018-11-06T20:22:36.408Z,1541535756.408 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20181106T201052/Courier0000.lzma
2018-11-06T20:22:37.208Z,1541535757.208 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201052/Courier0000.lzma.bak
2018-11-06T20:22:37.209Z,1541535757.209 [DataOverHttps](INFO): SBD MOMSN=8797143
2018-11-06T20:22:46.422Z,1541535766.422 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20181106T201203/Courier0000.lzma
2018-11-06T20:22:47.212Z,1541535767.212 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201203/Courier0000.lzma.bak
2018-11-06T20:22:47.212Z,1541535767.212 [DataOverHttps](INFO): SBD MOMSN=8797150
2018-11-06T20:22:51.951Z,1541535771.951 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-11-06T20:22:51.951Z,1541535771.951 [NAL9602] Data Fault, FailCount= 1
2018-11-06T20:22:51.951Z,1541535771.951 [NAL9602](ERROR): Data Fault
2018-11-06T20:22:52.036Z,1541535772.036 [CBIT](ERROR): Data Fault in component: NAL9602
2018-11-06T20:22:52.055Z,1541535772.055 [NAL9602](INFO): Powering down
2018-11-06T20:22:52.949Z,1541535772.949 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T20:22:52.950Z,1541535772.950 [NAL9602] No Fault, FailCount= 1
2018-11-06T20:23:00.735Z,1541535780.735 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181106T201203/Courier0004.lzma
2018-11-06T20:23:01.497Z,1541535781.497 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201203/Courier0004.lzma.bak
2018-11-06T20:23:01.497Z,1541535781.497 [DataOverHttps](INFO): SBD MOMSN=8797157
2018-11-06T20:23:13.956Z,1541535793.956 [DataOverHttps](INFO): Sending 2818 bytes from file Logs/20181106T093237/Express0101.lzma
2018-11-06T20:23:14.721Z,1541535794.721 [DataOverHttps](INFO): Moved sent file to Logs/20181106T093237/Express0101.lzma.bak
2018-11-06T20:23:14.721Z,1541535794.721 [DataOverHttps](INFO): SBD MOMSN=8797160
2018-11-06T20:23:24.621Z,1541535804.621 [NAL9602](INFO): Powering up NAL9602
2018-11-06T20:23:24.689Z,1541535804.689 [DataOverHttps](INFO): Sending 2246 bytes from file Logs/20181106T093237/Express0104.lzma
2018-11-06T20:23:25.481Z,1541535805.481 [DataOverHttps](INFO): Moved sent file to Logs/20181106T093237/Express0104.lzma.bak
2018-11-06T20:23:25.481Z,1541535805.481 [DataOverHttps](INFO): SBD MOMSN=8797562
2018-11-06T20:23:27.679Z,1541535807.679 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-11-06T20:23:27.679Z,1541535807.679 [BPC1] Data Fault, FailCount= 1
2018-11-06T20:23:27.679Z,1541535807.679 [BPC1](ERROR): Data Fault
2018-11-06T20:23:27.729Z,1541535807.729 [CBIT](ERROR): Data Fault in component: BPC1
2018-11-06T20:23:36.787Z,1541535816.787 [DataOverHttps](INFO): Sending 1045 bytes from file Logs/20181106T191436/Express0001.lzma
2018-11-06T20:23:37.183Z,1541535817.183 [NAL9602](INFO): NAL9602 initialized
2018-11-06T20:23:37.580Z,1541535817.580 [DataOverHttps](INFO): Moved sent file to Logs/20181106T191436/Express0001.lzma.bak
2018-11-06T20:23:37.581Z,1541535817.581 [DataOverHttps](INFO): SBD MOMSN=8797898
2018-11-06T20:23:48.323Z,1541535828.323 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:23:48.323Z,1541535828.323 [PNI_TCM] No Fault, FailCount= 5
2018-11-06T20:23:48.338Z,1541535828.338 [DataOverHttps](INFO): Sending 477 bytes from file Logs/20181106T191436/Express0005.lzma
2018-11-06T20:23:49.132Z,1541535829.132 [DataOverHttps](INFO): Moved sent file to Logs/20181106T191436/Express0005.lzma.bak
2018-11-06T20:23:49.132Z,1541535829.132 [DataOverHttps](INFO): SBD MOMSN=8797926
2018-11-06T20:24:00.921Z,1541535840.921 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:24:00.921Z,1541535840.921 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T20:24:00.921Z,1541535840.921 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:24:00.941Z,1541535840.941 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:24:00.996Z,1541535840.996 [DataOverHttps](INFO): Sending 471 bytes from file Logs/20181106T192120/Express0001.lzma
2018-11-06T20:24:01.030Z,1541535841.030 [PNI_TCM](INFO): Powering down
2018-11-06T20:24:01.752Z,1541535841.752 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192120/Express0001.lzma.bak
2018-11-06T20:24:01.752Z,1541535841.752 [DataOverHttps](INFO): SBD MOMSN=8797944
2018-11-06T20:24:03.796Z,1541535843.796 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:24:03.796Z,1541535843.796 [PNI_TCM] No Fault, FailCount= 1
2018-11-06T20:24:11.684Z,1541535851.684 [DataOverHttps](INFO): Sending 470 bytes from file Logs/20181106T192145/Express0001.lzma
2018-11-06T20:24:12.480Z,1541535852.480 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192145/Express0001.lzma.bak
2018-11-06T20:24:12.480Z,1541535852.480 [DataOverHttps](INFO): SBD MOMSN=8797954
2018-11-06T20:24:14.519Z,1541535854.519 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:24:14.519Z,1541535854.519 [PNI_TCM] Data Fault, FailCount= 2
2018-11-06T20:24:14.519Z,1541535854.519 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:24:14.572Z,1541535854.572 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:24:14.633Z,1541535854.633 [PNI_TCM](INFO): Powering down
2018-11-06T20:24:17.824Z,1541535857.824 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:24:17.824Z,1541535857.824 [PNI_TCM] No Fault, FailCount= 2
2018-11-06T20:24:22.432Z,1541535862.432 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:24:22.432Z,1541535862.432 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-11-06T20:24:22.432Z,1541535862.432 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:24:22.445Z,1541535862.445 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:24:22.514Z,1541535862.514 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20181106T192210/Express0001.lzma
2018-11-06T20:24:22.591Z,1541535862.591 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:24:22.591Z,1541535862.591 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-11-06T20:24:22.930Z,1541535862.930 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:24:22.931Z,1541535862.931 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:24:22.931Z,1541535862.931 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:24:23.284Z,1541535863.284 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192210/Express0001.lzma.bak
2018-11-06T20:24:23.284Z,1541535863.284 [DataOverHttps](INFO): SBD MOMSN=8797964
2018-11-06T20:24:28.478Z,1541535868.478 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:24:28.478Z,1541535868.478 [PNI_TCM] Data Fault, FailCount= 3
2018-11-06T20:24:28.479Z,1541535868.479 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:24:28.497Z,1541535868.497 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:24:28.594Z,1541535868.594 [PNI_TCM](INFO): Powering down
2018-11-06T20:24:29.952Z,1541535869.952 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:24:29.952Z,1541535869.952 [PNI_TCM] No Fault, FailCount= 3
2018-11-06T20:24:33.592Z,1541535873.592 [DataOverHttps](INFO): Sending 472 bytes from file Logs/20181106T192235/Express0001.lzma
2018-11-06T20:24:34.376Z,1541535874.376 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192235/Express0001.lzma.bak
2018-11-06T20:24:34.377Z,1541535874.377 [DataOverHttps](INFO): SBD MOMSN=8797974
2018-11-06T20:24:36.425Z,1541535876.425 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:24:36.425Z,1541535876.425 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:24:39.529Z,1541535879.529 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:24:39.529Z,1541535879.529 [PNI_TCM] Data Fault, FailCount= 4
2018-11-06T20:24:39.529Z,1541535879.529 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:24:39.588Z,1541535879.588 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:24:39.661Z,1541535879.661 [PNI_TCM](INFO): Powering down
2018-11-06T20:24:40.857Z,1541535880.857 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:24:40.857Z,1541535880.857 [PNI_TCM] No Fault, FailCount= 4
2018-11-06T20:24:44.351Z,1541535884.351 [DataOverHttps](INFO): Sending 792 bytes from file Logs/20181106T192628/Express0001.lzma
2018-11-06T20:24:45.148Z,1541535885.148 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192628/Express0001.lzma.bak
2018-11-06T20:24:45.148Z,1541535885.148 [DataOverHttps](INFO): SBD MOMSN=8797984
2018-11-06T20:24:47.176Z,1541535887.176 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:24:47.176Z,1541535887.176 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:24:47.176Z,1541535887.176 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:24:47.200Z,1541535887.200 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:24:47.414Z,1541535887.414 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:24:50.300Z,1541535890.300 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:24:50.300Z,1541535890.300 [PNI_TCM] Data Fault, FailCount= 5
2018-11-06T20:24:50.300Z,1541535890.300 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:24:50.320Z,1541535890.320 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:24:50.320Z,1541535890.320 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-06T20:24:50.417Z,1541535890.417 [PNI_TCM](INFO): Powering down
2018-11-06T20:24:50.526Z,1541535890.526 [CBIT](INFO): Critical error at 20181106T202450
2018-11-06T20:24:50.529Z,1541535890.529 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:24:50.529Z,1541535890.529 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:24:55.877Z,1541535895.877 [DataOverHttps](INFO): Sending 756 bytes from file Logs/20181106T192723/Express0001.lzma
2018-11-06T20:24:56.672Z,1541535896.672 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192723/Express0001.lzma.bak
2018-11-06T20:24:56.672Z,1541535896.672 [DataOverHttps](INFO): SBD MOMSN=8798005
2018-11-06T20:25:01.839Z,1541535901.839 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:25:01.839Z,1541535901.839 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:25:01.839Z,1541535901.839 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:25:01.859Z,1541535901.859 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:25:02.075Z,1541535902.075 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:25:02.734Z,1541535902.734 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:25:02.734Z,1541535902.734 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:25:06.624Z,1541535906.624 [DataOverHttps](INFO): Sending 442 bytes from file Logs/20181106T192812/Express0001.lzma
2018-11-06T20:25:07.392Z,1541535907.392 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192812/Express0001.lzma.bak
2018-11-06T20:25:07.393Z,1541535907.393 [DataOverHttps](INFO): SBD MOMSN=8798025
2018-11-06T20:25:13.457Z,1541535913.457 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:25:13.457Z,1541535913.457 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:25:13.458Z,1541535913.458 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:25:13.483Z,1541535913.483 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:25:13.484Z,1541535913.484 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:25:13.970Z,1541535913.970 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:25:17.379Z,1541535917.379 [DataOverHttps](INFO): Sending 472 bytes from file Logs/20181106T192837/Express0001.lzma
2018-11-06T20:25:18.149Z,1541535918.149 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192837/Express0001.lzma.bak
2018-11-06T20:25:18.162Z,1541535918.162 [DataOverHttps](INFO): SBD MOMSN=8798034
2018-11-06T20:25:28.084Z,1541535928.084 [DataOverHttps](INFO): Sending 982 bytes from file Logs/20181106T192902/Express0001.lzma
2018-11-06T20:25:28.853Z,1541535928.853 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192902/Express0001.lzma.bak
2018-11-06T20:25:28.853Z,1541535928.853 [DataOverHttps](INFO): SBD MOMSN=8798044
2018-11-06T20:25:39.637Z,1541535939.637 [DataOverHttps](INFO): Sending 477 bytes from file Logs/20181106T192902/Express0005.lzma
2018-11-06T20:25:40.432Z,1541535940.432 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192902/Express0005.lzma.bak
2018-11-06T20:25:40.433Z,1541535940.433 [DataOverHttps](INFO): SBD MOMSN=8798070
2018-11-06T20:25:51.394Z,1541535951.394 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20181106T192902/Express0008.lzma
2018-11-06T20:25:52.192Z,1541535952.192 [DataOverHttps](INFO): Moved sent file to Logs/20181106T192902/Express0008.lzma.bak
2018-11-06T20:25:52.192Z,1541535952.192 [DataOverHttps](INFO): SBD MOMSN=8798093
2018-11-06T20:26:02.123Z,1541535962.123 [DataOverHttps](INFO): Sending 1007 bytes from file Logs/20181106T193849/Express0001.lzma
2018-11-06T20:26:02.892Z,1541535962.892 [DataOverHttps](INFO): Moved sent file to Logs/20181106T193849/Express0001.lzma.bak
2018-11-06T20:26:02.892Z,1541535962.892 [DataOverHttps](INFO): SBD MOMSN=8798097
2018-11-06T20:26:12.836Z,1541535972.836 [DataOverHttps](INFO): Sending 459 bytes from file Logs/20181106T193849/Express0005.lzma
2018-11-06T20:26:13.636Z,1541535973.636 [DataOverHttps](INFO): Moved sent file to Logs/20181106T193849/Express0005.lzma.bak
2018-11-06T20:26:13.636Z,1541535973.636 [DataOverHttps](INFO): SBD MOMSN=8798124
2018-11-06T20:26:23.537Z,1541535983.537 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:26:23.537Z,1541535983.537 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-11-06T20:26:23.537Z,1541535983.537 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:26:23.590Z,1541535983.590 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:26:23.620Z,1541535983.620 [DataOverHttps](INFO): Sending 513 bytes from file Logs/20181106T194624/Express0001.lzma
2018-11-06T20:26:23.695Z,1541535983.695 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:26:23.695Z,1541535983.695 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-11-06T20:26:24.059Z,1541535984.059 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:26:24.060Z,1541535984.060 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:26:24.060Z,1541535984.060 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:26:24.408Z,1541535984.408 [DataOverHttps](INFO): Moved sent file to Logs/20181106T194624/Express0001.lzma.bak
2018-11-06T20:26:24.408Z,1541535984.408 [DataOverHttps](INFO): SBD MOMSN=8798140
2018-11-06T20:26:35.755Z,1541535995.755 [DataOverHttps](INFO): Sending 471 bytes from file Logs/20181106T194657/Express0001.lzma
2018-11-06T20:26:36.553Z,1541535996.553 [DataOverHttps](INFO): Moved sent file to Logs/20181106T194657/Express0001.lzma.bak
2018-11-06T20:26:36.553Z,1541535996.553 [DataOverHttps](INFO): SBD MOMSN=8798152
2018-11-06T20:26:47.702Z,1541536007.702 [DataOverHttps](INFO): Sending 473 bytes from file Logs/20181106T194721/Express0001.lzma
2018-11-06T20:26:48.472Z,1541536008.472 [DataOverHttps](INFO): Moved sent file to Logs/20181106T194721/Express0001.lzma.bak
2018-11-06T20:26:48.473Z,1541536008.473 [DataOverHttps](INFO): SBD MOMSN=8798162
2018-11-06T20:26:59.650Z,1541536019.650 [DataOverHttps](INFO): Sending 439 bytes from file Logs/20181106T194746/Express0001.lzma
2018-11-06T20:27:00.420Z,1541536020.420 [DataOverHttps](INFO): Moved sent file to Logs/20181106T194746/Express0001.lzma.bak
2018-11-06T20:27:00.421Z,1541536020.421 [DataOverHttps](INFO): SBD MOMSN=8798172
2018-11-06T20:27:10.435Z,1541536030.435 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T194812/Express0001.lzma
2018-11-06T20:27:11.232Z,1541536031.232 [DataOverHttps](INFO): Moved sent file to Logs/20181106T194812/Express0001.lzma.bak
2018-11-06T20:27:11.232Z,1541536031.232 [DataOverHttps](INFO): SBD MOMSN=8798181
2018-11-06T20:27:16.488Z,1541536036.488 [CBIT](INFO): Clearing failed state for component DropWeight
2018-11-06T20:27:16.488Z,1541536036.488 [DropWeight] No Fault, FailCount= 1
2018-11-06T20:27:21.236Z,1541536041.236 [DataOverHttps](INFO): Sending 1157 bytes from file Logs/20181106T195218/Express0001.lzma
2018-11-06T20:27:22.032Z,1541536042.032 [DataOverHttps](INFO): Moved sent file to Logs/20181106T195218/Express0001.lzma.bak
2018-11-06T20:27:22.032Z,1541536042.032 [DataOverHttps](INFO): SBD MOMSN=8798183
2018-11-06T20:27:31.945Z,1541536051.945 [DataOverHttps](INFO): Sending 492 bytes from file Logs/20181106T195218/Express0005.lzma
2018-11-06T20:27:32.744Z,1541536052.744 [DataOverHttps](INFO): Moved sent file to Logs/20181106T195218/Express0005.lzma.bak
2018-11-06T20:27:32.744Z,1541536052.744 [DataOverHttps](INFO): SBD MOMSN=8798218
2018-11-06T20:27:42.684Z,1541536062.684 [DataOverHttps](INFO): Sending 487 bytes from file Logs/20181106T200005/Express0001.lzma
2018-11-06T20:27:43.472Z,1541536063.472 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200005/Express0001.lzma.bak
2018-11-06T20:27:43.473Z,1541536063.473 [DataOverHttps](INFO): SBD MOMSN=8798240
2018-11-06T20:27:43.579Z,1541536063.579 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:27:43.580Z,1541536063.580 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:27:53.635Z,1541536073.635 [DataOverHttps](INFO): Sending 437 bytes from file Logs/20181106T200038/Express0001.lzma
2018-11-06T20:27:54.404Z,1541536074.404 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200038/Express0001.lzma.bak
2018-11-06T20:27:54.405Z,1541536074.405 [DataOverHttps](INFO): SBD MOMSN=8798251
2018-11-06T20:27:56.431Z,1541536076.431 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:27:56.431Z,1541536076.431 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:27:56.431Z,1541536076.431 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:27:56.457Z,1541536076.457 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:27:56.663Z,1541536076.663 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:27:59.705Z,1541536079.705 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:27:59.705Z,1541536079.705 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:28:10.651Z,1541536090.651 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:28:10.651Z,1541536090.651 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:28:10.651Z,1541536090.651 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:28:10.695Z,1541536090.695 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:28:10.734Z,1541536090.734 [DataOverHttps](INFO): Sending 438 bytes from file Logs/20181106T200103/Express0001.lzma
2018-11-06T20:28:10.907Z,1541536090.907 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:28:11.508Z,1541536091.508 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200103/Express0001.lzma.bak
2018-11-06T20:28:11.508Z,1541536091.508 [DataOverHttps](INFO): SBD MOMSN=8798262
2018-11-06T20:28:11.593Z,1541536091.593 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:28:11.593Z,1541536091.593 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:28:21.656Z,1541536101.656 [DataOverHttps](INFO): Sending 470 bytes from file Logs/20181106T200128/Express0001.lzma
2018-11-06T20:28:22.456Z,1541536102.456 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200128/Express0001.lzma.bak
2018-11-06T20:28:22.456Z,1541536102.456 [DataOverHttps](INFO): SBD MOMSN=8798274
2018-11-06T20:28:24.481Z,1541536104.481 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:28:24.481Z,1541536104.481 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:28:24.481Z,1541536104.481 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:28:24.488Z,1541536104.488 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:28:24.488Z,1541536104.488 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-11-06T20:28:24.488Z,1541536104.488 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:28:24.501Z,1541536104.501 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:28:24.502Z,1541536104.502 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:28:24.502Z,1541536104.502 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:28:24.714Z,1541536104.714 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:28:24.768Z,1541536104.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:28:24.768Z,1541536104.768 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-11-06T20:28:27.614Z,1541536107.614 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:28:27.615Z,1541536107.615 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:28:27.615Z,1541536107.615 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:28:32.403Z,1541536112.403 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20181106T200152/Express0001.lzma
2018-11-06T20:28:33.201Z,1541536113.201 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200152/Express0001.lzma.bak
2018-11-06T20:28:33.203Z,1541536113.203 [DataOverHttps](INFO): SBD MOMSN=8798284
2018-11-06T20:28:44.363Z,1541536124.363 [DataOverHttps](INFO): Sending 1050 bytes from file Logs/20181106T200217/Express0001.lzma
2018-11-06T20:28:45.160Z,1541536125.160 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200217/Express0001.lzma.bak
2018-11-06T20:28:45.160Z,1541536125.160 [DataOverHttps](INFO): SBD MOMSN=8798294
2018-11-06T20:28:55.107Z,1541536135.107 [DataOverHttps](INFO): Sending 488 bytes from file Logs/20181106T200217/Express0005.lzma
2018-11-06T20:28:55.904Z,1541536135.904 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200217/Express0005.lzma.bak
2018-11-06T20:28:55.904Z,1541536135.904 [DataOverHttps](INFO): SBD MOMSN=8798322
2018-11-06T20:29:08.729Z,1541536148.729 [DataOverHttps](INFO): Sending 515 bytes from file Logs/20181106T200906/Express0001.lzma
2018-11-06T20:29:09.529Z,1541536149.529 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200906/Express0001.lzma.bak
2018-11-06T20:29:09.529Z,1541536149.529 [DataOverHttps](INFO): SBD MOMSN=8798342
2018-11-06T20:29:20.773Z,1541536160.773 [DataOverHttps](INFO): Sending 473 bytes from file Logs/20181106T200939/Express0001.lzma
2018-11-06T20:29:21.572Z,1541536161.572 [DataOverHttps](INFO): Moved sent file to Logs/20181106T200939/Express0001.lzma.bak
2018-11-06T20:29:21.572Z,1541536161.572 [DataOverHttps](INFO): SBD MOMSN=8798354
2018-11-06T20:29:32.477Z,1541536172.477 [DataOverHttps](INFO): Sending 470 bytes from file Logs/20181106T201004/Express0001.lzma
2018-11-06T20:29:33.276Z,1541536173.276 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201004/Express0001.lzma.bak
2018-11-06T20:29:33.276Z,1541536173.276 [DataOverHttps](INFO): SBD MOMSN=8798364
2018-11-06T20:29:44.423Z,1541536184.423 [DataOverHttps](INFO): Sending 471 bytes from file Logs/20181106T201028/Express0001.lzma
2018-11-06T20:29:45.220Z,1541536185.220 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201028/Express0001.lzma.bak
2018-11-06T20:29:45.220Z,1541536185.220 [DataOverHttps](INFO): SBD MOMSN=8798374
2018-11-06T20:29:50.471Z,1541536190.471 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:29:50.472Z,1541536190.472 [PNI_TCM] No Fault, FailCount= 5
2018-11-06T20:29:56.428Z,1541536196.428 [DataOverHttps](INFO): Sending 768 bytes from file Logs/20181106T201052/Express0001.lzma
2018-11-06T20:29:57.216Z,1541536197.216 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201052/Express0001.lzma.bak
2018-11-06T20:29:57.216Z,1541536197.216 [DataOverHttps](INFO): SBD MOMSN=8798384
2018-11-06T20:29:59.263Z,1541536199.263 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:29:59.263Z,1541536199.263 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T20:29:59.263Z,1541536199.263 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:29:59.288Z,1541536199.288 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:29:59.375Z,1541536199.375 [PNI_TCM](INFO): Powering down
2018-11-06T20:30:02.587Z,1541536202.587 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:30:02.587Z,1541536202.587 [PNI_TCM] No Fault, FailCount= 1
2018-11-06T20:30:08.434Z,1541536208.434 [DataOverHttps](INFO): Sending 1060 bytes from file Logs/20181106T201203/Express0001.lzma
2018-11-06T20:30:09.196Z,1541536209.196 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201203/Express0001.lzma.bak
2018-11-06T20:30:09.197Z,1541536209.197 [DataOverHttps](INFO): SBD MOMSN=8798405
2018-11-06T20:30:11.281Z,1541536211.281 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:30:11.281Z,1541536211.281 [PNI_TCM] Data Fault, FailCount= 2
2018-11-06T20:30:11.281Z,1541536211.281 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:30:11.333Z,1541536211.333 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:30:11.389Z,1541536211.389 [PNI_TCM](INFO): Powering down
2018-11-06T20:30:14.627Z,1541536214.627 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:30:14.627Z,1541536214.627 [PNI_TCM] No Fault, FailCount= 2
2018-11-06T20:30:19.286Z,1541536219.286 [DataOverHttps](INFO): Sending 544 bytes from file Logs/20181106T201203/Express0005.lzma
2018-11-06T20:30:20.851Z,1541536220.851 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201203/Express0005.lzma.bak
2018-11-06T20:30:20.852Z,1541536220.852 [DataOverHttps](INFO): SBD MOMSN=8798435
2018-11-06T20:30:22.684Z,1541536222.684 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T20:30:22.684Z,1541536222.684 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T20:30:22.684Z,1541536222.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T20:30:25.815Z,1541536225.815 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:30:25.815Z,1541536225.815 [PNI_TCM] Data Fault, FailCount= 3
2018-11-06T20:30:25.815Z,1541536225.815 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:30:25.839Z,1541536225.839 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:30:25.939Z,1541536225.939 [PNI_TCM](INFO): Powering down
2018-11-06T20:30:27.102Z,1541536227.102 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:30:27.102Z,1541536227.102 [PNI_TCM] No Fault, FailCount= 3
2018-11-06T20:30:27.896Z,1541536227.896 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:30:27.896Z,1541536227.896 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-11-06T20:30:27.896Z,1541536227.896 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:30:27.941Z,1541536227.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:30:28.330Z,1541536228.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:30:28.331Z,1541536228.331 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-11-06T20:30:28.696Z,1541536228.696 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:30:28.697Z,1541536228.697 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:30:28.697Z,1541536228.697 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:30:34.683Z,1541536234.683 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:30:34.683Z,1541536234.683 [PNI_TCM] Data Fault, FailCount= 4
2018-11-06T20:30:34.683Z,1541536234.683 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:30:34.732Z,1541536234.732 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:30:35.075Z,1541536235.075 [PNI_TCM](INFO): Powering down
2018-11-06T20:30:36.299Z,1541536236.299 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:30:36.299Z,1541536236.299 [PNI_TCM] No Fault, FailCount= 4
2018-11-06T20:30:43.884Z,1541536243.884 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:30:43.884Z,1541536243.884 [PNI_TCM] Data Fault, FailCount= 5
2018-11-06T20:30:43.884Z,1541536243.884 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:30:43.908Z,1541536243.908 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:30:43.908Z,1541536243.908 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-06T20:30:44.268Z,1541536244.268 [PNI_TCM](INFO): Powering down
2018-11-06T20:30:44.394Z,1541536244.394 [CBIT](INFO): Critical error at 20181106T203043
2018-11-06T20:30:54.745Z,1541536254.745 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:30:54.745Z,1541536254.745 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:31:05.467Z,1541536265.467 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:31:05.467Z,1541536265.467 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:31:05.467Z,1541536265.467 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:31:05.515Z,1541536265.515 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:31:05.982Z,1541536265.982 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:31:06.705Z,1541536266.705 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:31:06.705Z,1541536266.705 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:31:17.467Z,1541536277.467 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:31:17.467Z,1541536277.467 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:31:17.467Z,1541536277.467 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:31:17.502Z,1541536277.502 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:31:17.986Z,1541536277.986 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:31:18.711Z,1541536278.711 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:31:18.711Z,1541536278.711 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:31:29.399Z,1541536289.399 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:31:29.399Z,1541536289.399 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:31:29.399Z,1541536289.399 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:31:29.421Z,1541536289.421 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:31:29.421Z,1541536289.421 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:31:29.934Z,1541536289.934 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:31:43.288Z,1541536303.288 [CommandLine](IMPORTANT): got command restart application
2018-11-06T20:31:44.297Z,1541536304.297 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:44.297Z,1541536304.297 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.342Z,1541536304.342 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-06T20:31:44.342Z,1541536304.342 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.343Z,1541536304.343 [CommandLine](INFO): Join timeout helper Thread ID is 2559
2018-11-06T20:31:44.349Z,1541536304.349 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-06T20:31:44.349Z,1541536304.349 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.349Z,1541536304.349 [NavChartDb](INFO): Join timeout helper Thread ID is 2560
2018-11-06T20:31:44.534Z,1541536304.534 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:44.534Z,1541536304.534 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.546Z,1541536304.546 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-06T20:31:44.546Z,1541536304.546 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.547Z,1541536304.547 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2561
2018-11-06T20:31:44.810Z,1541536304.810 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:44.810Z,1541536304.810 [WetLabsBB2FL](INFO): Powering down
2018-11-06T20:31:44.811Z,1541536304.811 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.826Z,1541536304.826 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-11-06T20:31:44.826Z,1541536304.826 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.827Z,1541536304.827 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2562
2018-11-06T20:31:44.827Z,1541536304.827 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:44.920Z,1541536304.920 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T20:31:44.921Z,1541536304.921 [CTD_Seabird](INFO): Powering down
2018-11-06T20:31:44.922Z,1541536304.922 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.923Z,1541536304.923 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-11-06T20:31:44.923Z,1541536304.923 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:44.923Z,1541536304.923 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 2564
2018-11-06T20:31:45.090Z,1541536305.090 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:45.090Z,1541536305.090 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:31:45.180Z,1541536305.180 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:31:45.181Z,1541536305.181 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:45.187Z,1541536305.187 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-06T20:31:45.187Z,1541536305.187 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:45.188Z,1541536305.188 [Radio_Surface](INFO): Join timeout helper Thread ID is 2566
2018-11-06T20:31:45.574Z,1541536305.574 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:45.574Z,1541536305.574 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:45.587Z,1541536305.587 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-06T20:31:45.587Z,1541536305.587 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:45.587Z,1541536305.587 [logger](INFO): Join timeout helper Thread ID is 2567
2018-11-06T20:31:45.606Z,1541536305.606 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:45.606Z,1541536305.606 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:45.627Z,1541536305.627 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-06T20:31:45.627Z,1541536305.627 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:45.627Z,1541536305.627 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-06T20:31:45.627Z,1541536305.627 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:45.627Z,1541536305.627 [controlThread](INFO): Join timeout helper Thread ID is 2568
2018-11-06T20:31:45.766Z,1541536305.766 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:31:45.766Z,1541536305.766 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-06T20:31:45.767Z,1541536305.767 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-11-06T20:31:45.887Z,1541536305.887 [NAL9602](INFO): Powering down
2018-11-06T20:31:45.888Z,1541536305.888 [PNI_TCM](INFO): Powering down
2018-11-06T20:31:45.889Z,1541536305.889 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:31:45.892Z,1541536305.892 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T20:31:45.893Z,1541536305.893 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-06T20:31:45.894Z,1541536305.894 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-06T20:31:45.894Z,1541536305.894 [MissionManager](INFO): Uninitializing Mission Default
2018-11-06T20:31:45.894Z,1541536305.894 [Default] Stopped
2018-11-06T20:31:45.895Z,1541536305.895 [Default](DEBUG): Aggregate::uninitialize Default
2018-11-06T20:31:45.895Z,1541536305.895 [Default:B.GoToSurface] Stopped
2018-11-06T20:31:45.895Z,1541536305.895 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T20:31:45.895Z,1541536305.895 [Default:CheckIn] Stopped
2018-11-06T20:31:45.895Z,1541536305.895 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T20:31:45.895Z,1541536305.895 [Default:CheckIn:C.Wait] Stopped
2018-11-06T20:31:45.895Z,1541536305.895 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T20:31:45.899Z,1541536305.899 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-06T20:31:45.899Z,1541536305.899 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-06T20:31:45.900Z,1541536305.900 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-06T20:31:45.900Z,1541536305.900 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-06T20:31:45.900Z,1541536305.900 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T20:31:45.900Z,1541536305.900 [BuoyancyServo](INFO): Powering down
2018-11-06T20:31:45.914Z,1541536305.914 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-06T20:31:45.914Z,1541536305.914 [ElevatorServo](INFO): Powering down
2018-11-06T20:31:45.915Z,1541536305.915 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-06T20:31:45.915Z,1541536305.915 [MassServo](INFO): Powering down
2018-11-06T20:31:45.916Z,1541536305.916 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-06T20:31:45.916Z,1541536305.916 [RudderServo](INFO): Powering down
2018-11-06T20:31:45.917Z,1541536305.917 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-06T20:31:45.917Z,1541536305.917 [ThrusterServo](INFO): Powering down
2018-11-06T20:31:45.918Z,1541536305.918 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-06T20:31:45.919Z,1541536305.919 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-06T20:31:45.919Z,1541536305.919 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-06T20:31:45.920Z,1541536305.920 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:46.017Z,1541536306.017 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:46.023Z,1541536306.023 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:46.068Z,1541536306.068 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:46.073Z,1541536306.073 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:46.131Z,1541536306.131 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:31:46.180Z,1541536306.180 [logger ThreadHandler](INFO): Thread cancelled.