2017-12-13T19:06:15.090Z,1513191975.090 [Supervisor](DEBUG): Initializing supervisor.
2017-12-13T19:06:15.093Z,1513191975.093 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-12-13T19:06:15.093Z,1513191975.093 [SyncHandler](INFO): Protected caller Thread ID is 1174
2017-12-13T19:06:15.094Z,1513191975.094 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-12-13T19:06:15.095Z,1513191975.095 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-12-13T19:06:15.095Z,1513191975.095 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1175
2017-12-13T19:06:15.098Z,1513191975.098 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-12-13T19:06:15.109Z,1513191975.109 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-12-13T19:06:15.110Z,1513191975.110 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-12-13T19:06:15.111Z,1513191975.111 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1176
2017-12-13T19:06:15.111Z,1513191975.111 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-12-13T19:06:15.112Z,1513191975.112 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-12-13T19:06:15.113Z,1513191975.113 [logger ThreadHandler](INFO): Protected caller Thread ID is 1177
2017-12-13T19:06:15.115Z,1513191975.115 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-12-13T19:06:15.115Z,1513191975.115 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-12-13T19:06:15.117Z,1513191975.117 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-12-13T19:06:15.302Z,1513191975.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-12-13T19:06:15.302Z,1513191975.302 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-12-13T19:06:15.441Z,1513191975.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-12-13T19:06:15.442Z,1513191975.442 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-12-13T19:06:15.777Z,1513191975.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-12-13T19:06:15.778Z,1513191975.778 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-12-13T19:06:15.913Z,1513191975.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-12-13T19:06:15.914Z,1513191975.914 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-12-13T19:06:15.995Z,1513191975.995 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-12-13T19:06:16.194Z,1513191976.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-12-13T19:06:16.195Z,1513191976.195 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-12-13T19:06:16.293Z,1513191976.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-12-13T19:06:16.293Z,1513191976.293 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-12-13T19:06:16.621Z,1513191976.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-12-13T19:06:16.622Z,1513191976.622 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-12-13T19:06:16.971Z,1513191976.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-12-13T19:06:16.972Z,1513191976.972 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-12-13T19:06:17.275Z,1513191977.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-12-13T19:06:17.276Z,1513191977.276 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-12-13T19:06:17.780Z,1513191977.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-12-13T19:06:17.781Z,1513191977.781 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-12-13T19:06:17.983Z,1513191977.983 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-12-13T19:06:17.983Z,1513191977.983 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-12-13T19:06:18.082Z,1513191978.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-12-13T19:06:18.082Z,1513191978.082 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-12-13T19:06:18.555Z,1513191978.555 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-12-13T19:06:18.556Z,1513191978.556 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-12-13T19:06:18.663Z,1513191978.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-12-13T19:06:18.665Z,1513191978.665 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-12-13T19:06:18.665Z,1513191978.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-12-13T19:06:18.899Z,1513191978.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-12-13T19:06:18.899Z,1513191978.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-12-13T19:06:19.000Z,1513191979.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-12-13T19:06:19.091Z,1513191979.091 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-12-13T19:06:19.194Z,1513191979.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-12-13T19:06:19.280Z,1513191979.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-12-13T19:06:19.407Z,1513191979.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-12-13T19:06:19.572Z,1513191979.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-12-13T19:06:19.684Z,1513191979.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-12-13T19:06:19.809Z,1513191979.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-12-13T19:06:19.893Z,1513191979.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-12-13T19:06:20.075Z,1513191980.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-12-13T19:06:20.151Z,1513191980.151 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-12-13T19:06:20.164Z,1513191980.164 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-12-13T19:06:20.291Z,1513191980.291 [SBIT](DEBUG): Construct Startup Built In Test.
2017-12-13T19:06:20.328Z,1513191980.328 [SBIT] Loaded
2017-12-13T19:06:20.328Z,1513191980.328 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-12-13T19:06:20.329Z,1513191980.329 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-12-13T19:06:20.365Z,1513191980.365 [IBIT] Loaded
2017-12-13T19:06:20.365Z,1513191980.365 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-12-13T19:06:20.368Z,1513191980.368 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-12-13T19:06:20.521Z,1513191980.521 [CBIT] Loaded
2017-12-13T19:06:20.521Z,1513191980.521 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-12-13T19:06:20.522Z,1513191980.522 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-12-13T19:06:20.522Z,1513191980.522 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-12-13T19:06:20.589Z,1513191980.589 [VerticalControl](DEBUG): Construct VerticalControl.
2017-12-13T19:06:20.687Z,1513191980.687 [VerticalControl] Loaded
2017-12-13T19:06:20.687Z,1513191980.687 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-12-13T19:06:20.688Z,1513191980.688 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-12-13T19:06:20.748Z,1513191980.748 [HorizontalControl] Loaded
2017-12-13T19:06:20.749Z,1513191980.749 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-12-13T19:06:20.749Z,1513191980.749 [SpeedControl](DEBUG): Construct SpeedControl.
2017-12-13T19:06:20.755Z,1513191980.755 [SpeedControl] Loaded
2017-12-13T19:06:20.755Z,1513191980.755 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-12-13T19:06:20.756Z,1513191980.756 [LoopControl](DEBUG): Construct LoopControl.
2017-12-13T19:06:20.756Z,1513191980.756 [LoopControl] Loaded
2017-12-13T19:06:20.756Z,1513191980.756 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-12-13T19:06:20.757Z,1513191980.757 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-12-13T19:06:20.757Z,1513191980.757 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-12-13T19:06:20.798Z,1513191980.798 [DepthRateCalculator] Loaded
2017-12-13T19:06:20.798Z,1513191980.798 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-12-13T19:06:20.804Z,1513191980.804 [PitchRateCalculator] Loaded
2017-12-13T19:06:20.804Z,1513191980.804 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-12-13T19:06:20.820Z,1513191980.820 [SpeedCalculator] Loaded
2017-12-13T19:06:20.820Z,1513191980.820 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-12-13T19:06:20.842Z,1513191980.842 [TempGradientCalculator] Loaded
2017-12-13T19:06:20.842Z,1513191980.842 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-12-13T19:06:20.857Z,1513191980.857 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-12-13T19:06:20.858Z,1513191980.858 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-12-13T19:06:20.863Z,1513191980.863 [YawRateCalculator] Loaded
2017-12-13T19:06:20.863Z,1513191980.863 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-12-13T19:06:20.892Z,1513191980.892 [ElevatorOffsetCalculator] Loaded
2017-12-13T19:06:20.892Z,1513191980.892 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2017-12-13T19:06:20.893Z,1513191980.893 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-12-13T19:06:20.894Z,1513191980.894 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-12-13T19:06:20.973Z,1513191980.973 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-12-13T19:06:20.974Z,1513191980.974 [StratificationFrontDetector](DEBUG): (re)initializing
2017-12-13T19:06:20.974Z,1513191980.974 [StratificationFrontDetector] Loaded
2017-12-13T19:06:20.974Z,1513191980.974 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-12-13T19:06:20.975Z,1513191980.975 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-12-13T19:06:20.975Z,1513191980.975 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-12-13T19:06:21.246Z,1513191981.246 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-12-13T19:06:21.246Z,1513191981.246 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-12-13T19:06:21.336Z,1513191981.336 [DeadReckonUsingSpeedCalculator] Loaded
2017-12-13T19:06:21.336Z,1513191981.336 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-12-13T19:06:21.391Z,1513191981.391 [DeadReckonWithRespectToSeafloor] Loaded
2017-12-13T19:06:21.391Z,1513191981.391 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2017-12-13T19:06:21.407Z,1513191981.407 [NavChart] Loaded
2017-12-13T19:06:21.408Z,1513191981.408 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-12-13T19:06:21.412Z,1513191981.412 [UniversalFixResidualReporter] Loaded
2017-12-13T19:06:21.412Z,1513191981.412 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-12-13T19:06:21.412Z,1513191981.412 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-12-13T19:06:21.413Z,1513191981.413 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-12-13T19:06:21.426Z,1513191981.426 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-12-13T19:06:21.427Z,1513191981.427 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-12-13T19:06:21.576Z,1513191981.576 [Aanderaa_O2] Loaded
2017-12-13T19:06:21.576Z,1513191981.576 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-12-13T19:06:21.588Z,1513191981.588 [CANONSampler] Loaded
2017-12-13T19:06:21.588Z,1513191981.588 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2017-12-13T19:06:21.665Z,1513191981.665 [CTD_NeilBrown] Loaded
2017-12-13T19:06:21.666Z,1513191981.666 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-12-13T19:06:21.667Z,1513191981.667 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406D14E0
2017-12-13T19:06:21.667Z,1513191981.667 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1259
2017-12-13T19:06:21.700Z,1513191981.700 [CTD_Seabird] Loaded
2017-12-13T19:06:21.700Z,1513191981.700 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-12-13T19:06:21.701Z,1513191981.701 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407014E0
2017-12-13T19:06:21.701Z,1513191981.701 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1260
2017-12-13T19:06:21.716Z,1513191981.716 [PAR_Licor] Loaded
2017-12-13T19:06:21.716Z,1513191981.716 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-12-13T19:06:21.765Z,1513191981.765 [WetLabsBB2FL] Loaded
2017-12-13T19:06:21.765Z,1513191981.765 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-12-13T19:06:21.766Z,1513191981.766 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407314E0
2017-12-13T19:06:21.766Z,1513191981.766 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1261
2017-12-13T19:06:21.810Z,1513191981.810 [WetLabsSeaOWL_UV_A] Loaded
2017-12-13T19:06:21.810Z,1513191981.810 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2017-12-13T19:06:21.811Z,1513191981.811 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 407614E0
2017-12-13T19:06:21.811Z,1513191981.811 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1262
2017-12-13T19:06:21.812Z,1513191981.812 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-12-13T19:06:21.813Z,1513191981.813 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-12-13T19:06:22.116Z,1513191982.116 [DataOverHttps] Loaded
2017-12-13T19:06:22.116Z,1513191982.116 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-12-13T19:06:22.130Z,1513191982.130 [Depth_Keller] Loaded
2017-12-13T19:06:22.130Z,1513191982.130 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-12-13T19:06:22.135Z,1513191982.135 [DropWeight] Loaded
2017-12-13T19:06:22.135Z,1513191982.135 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-12-13T19:06:22.383Z,1513191982.383 [NAL9602] Loaded
2017-12-13T19:06:22.383Z,1513191982.383 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-12-13T19:06:22.388Z,1513191982.388 [Onboard] Loaded
2017-12-13T19:06:22.413Z,1513191982.413 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-12-13T19:06:22.420Z,1513191982.420 [Radio_Surface] Loaded
2017-12-13T19:06:22.420Z,1513191982.420 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-12-13T19:06:22.422Z,1513191982.422 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0
2017-12-13T19:06:22.423Z,1513191982.423 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1263
2017-12-13T19:06:22.470Z,1513191982.470 [PNI_TCM] Loaded
2017-12-13T19:06:22.471Z,1513191982.471 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-12-13T19:06:24.265Z,1513191984.265 [BPC1] Loaded
2017-12-13T19:06:24.265Z,1513191984.265 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-12-13T19:06:24.266Z,1513191984.266 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-12-13T19:06:24.266Z,1513191984.266 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-12-13T19:06:24.362Z,1513191984.362 [BuoyancyServo] Loaded
2017-12-13T19:06:24.362Z,1513191984.362 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-12-13T19:06:24.373Z,1513191984.373 [ElevatorServo] Loaded
2017-12-13T19:06:24.374Z,1513191984.374 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-12-13T19:06:24.385Z,1513191984.385 [MassServo] Loaded
2017-12-13T19:06:24.385Z,1513191984.385 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-12-13T19:06:24.396Z,1513191984.396 [RudderServo] Loaded
2017-12-13T19:06:24.396Z,1513191984.396 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-12-13T19:06:24.407Z,1513191984.407 [ThrusterServo] Loaded
2017-12-13T19:06:24.407Z,1513191984.407 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-12-13T19:06:24.408Z,1513191984.408 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-12-13T19:06:24.408Z,1513191984.408 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-12-13T19:06:24.505Z,1513191984.505 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-12-13T19:06:24.505Z,1513191984.505 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-12-13T19:06:24.529Z,1513191984.529 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-12-13T19:06:24.533Z,1513191984.533 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-12-13T19:06:24.534Z,1513191984.534 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-12-13T19:06:24.541Z,1513191984.541 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-12-13T19:06:24.542Z,1513191984.542 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A4C4E0
2017-12-13T19:06:24.542Z,1513191984.542 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1264
2017-12-13T19:06:24.546Z,1513191984.546 [Supervisor](INFO): Main Thread ID is 795
2017-12-13T19:06:24.547Z,1513191984.547 [Supervisor](DEBUG): Running supervisor.
2017-12-13T19:06:24.547Z,1513191984.547 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1265
2017-12-13T19:06:24.550Z,1513191984.550 [controlThread ThreadHandler](INFO): Handler Thread ID is 1266
2017-12-13T19:06:24.550Z,1513191984.550 [controlThread](DEBUG): Initializing ControlThread
2017-12-13T19:06:24.551Z,1513191984.551 [SBIT](INFO): Initialize SBIT Component.
2017-12-13T19:06:24.551Z,1513191984.551 [SBIT](IMPORTANT): git: 2017-12-12
2017-12-13T19:06:24.552Z,1513191984.552 [SBIT](INFO): git hash: 18638d31f033e3fa0cb90b26256f5481d37f00cc
2017-12-13T19:06:24.552Z,1513191984.552 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-12-13T19:06:24.553Z,1513191984.553 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017
2017-12-13T19:06:24.554Z,1513191984.554 [SBIT](INFO): Beginning SBIT in 44.000000 seconds.
2017-12-13T19:06:24.555Z,1513191984.555 [IBIT](INFO): Initialize IBIT Component.
2017-12-13T19:06:24.556Z,1513191984.556 [CBIT](DEBUG): Initialize CBIT Component.
2017-12-13T19:06:24.556Z,1513191984.556 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-12-13T19:06:24.557Z,1513191984.557 [logger ThreadHandler](INFO): Handler Thread ID is 1267
2017-12-13T19:06:24.585Z,1513191984.585 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1268
2017-12-13T19:06:24.586Z,1513191984.586 [CTD_NeilBrown](INFO): Powering down
2017-12-13T19:06:24.618Z,1513191984.618 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1269
2017-12-13T19:06:24.619Z,1513191984.619 [CTD_Seabird](INFO): Initializing
2017-12-13T19:06:24.649Z,1513191984.649 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-12-13T19:06:24.652Z,1513191984.652 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-12-13T19:06:24.653Z,1513191984.653 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-12-13T19:06:24.653Z,1513191984.653 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-12-13T19:06:24.653Z,1513191984.653 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-12-13T19:06:24.654Z,1513191984.654 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-12-13T19:06:24.654Z,1513191984.654 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-12-13T19:06:24.654Z,1513191984.654 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-12-13T19:06:24.655Z,1513191984.655 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-12-13T19:06:24.656Z,1513191984.656 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-12-13T19:06:24.656Z,1513191984.656 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2017-12-13T19:06:24.657Z,1513191984.657 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-12-13T19:06:24.658Z,1513191984.658 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:06:24.658Z,1513191984.658 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:06:24.659Z,1513191984.659 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:06:24.659Z,1513191984.659 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:06:24.660Z,1513191984.660 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:06:24.660Z,1513191984.660 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-12-13T19:06:24.660Z,1513191984.660 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-12-13T19:06:24.671Z,1513191984.671 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-12-13T19:06:24.697Z,1513191984.697 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1271
2017-12-13T19:06:24.699Z,1513191984.699 [WetLabsBB2FL](INFO): Powering down
2017-12-13T19:06:24.704Z,1513191984.704 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1272
2017-12-13T19:06:24.706Z,1513191984.706 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1273
2017-12-13T19:06:24.712Z,1513191984.712 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1274
2017-12-13T19:06:24.716Z,1513191984.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-12-13T19:06:24.716Z,1513191984.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-12-13T19:06:24.716Z,1513191984.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-12-13T19:06:24.716Z,1513191984.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-12-13T19:06:24.717Z,1513191984.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-12-13T19:06:24.717Z,1513191984.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-12-13T19:06:24.717Z,1513191984.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-12-13T19:06:24.717Z,1513191984.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-12-13T19:06:24.718Z,1513191984.718 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-12-13T19:06:24.718Z,1513191984.718 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-12-13T19:06:24.718Z,1513191984.718 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-12-13T19:06:24.718Z,1513191984.718 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-12-13T19:06:24.718Z,1513191984.718 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-12-13T19:06:24.718Z,1513191984.718 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-12-13T19:06:24.719Z,1513191984.719 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-12-13T19:06:24.719Z,1513191984.719 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-12-13T19:06:24.759Z,1513191984.759 [Radio_Surface](INFO): Powering up
2017-12-13T19:06:24.799Z,1513191984.799 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-13T19:06:24.854Z,1513191984.854 [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>


2017-12-13T19:06:24.855Z,1513191984.855 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-12-13T19:06:24.948Z,1513191984.948 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-12-13T19:06:24.974Z,1513191984.974 [Default:A.Wait](DEBUG): Construct Wait.
2017-12-13T19:06:24.975Z,1513191984.975 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-12-13T19:06:25.031Z,1513191985.031 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-12-13T19:06:25.033Z,1513191985.033 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-12-13T19:06:25.038Z,1513191985.038 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-12-13T19:06:25.039Z,1513191985.039 [CTD_Seabird](INFO): Powering down
2017-12-13T19:06:25.079Z,1513191985.079 [Default:E.Execute](DEBUG): Construct Execute.
2017-12-13T19:06:25.082Z,1513191985.082 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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>


2017-12-13T19:06:25.093Z,1513191985.093 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-12-13T19:06:25.127Z,1513191985.127 [CANONSampler](INFO): Powering down
2017-12-13T19:06:25.255Z,1513191985.255 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-12-13T19:06:25.286Z,1513191985.286 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-12-13T19:06:25.468Z,1513191985.468 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-12-13T19:06:25.571Z,1513191985.571 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:25.599Z,1513191985.599 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:25.709Z,1513191985.709 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-12-13T19:06:25.717Z,1513191985.717 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-12-13T19:06:25.739Z,1513191985.739 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-12-13T19:06:25.745Z,1513191985.745 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-12-13T19:06:25.751Z,1513191985.751 [MassServo](DEBUG): Initializing EZServoServo.
2017-12-13T19:06:25.757Z,1513191985.757 [MassServo](DEBUG): Initializing MassServo.
2017-12-13T19:06:25.762Z,1513191985.762 [RudderServo](DEBUG): Initializing EZServoServo.
2017-12-13T19:06:25.769Z,1513191985.769 [RudderServo](DEBUG): Initializing RudderServo.
2017-12-13T19:06:25.790Z,1513191985.790 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-12-13T19:06:25.797Z,1513191985.797 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-12-13T19:06:26.202Z,1513191986.202 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:26.203Z,1513191986.203 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:26.331Z,1513191986.331 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:26.332Z,1513191986.332 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:26.833Z,1513191986.833 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:26.834Z,1513191986.834 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:27.260Z,1513191987.260 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:27.269Z,1513191987.269 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:27.612Z,1513191987.612 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:27.629Z,1513191987.629 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:28.012Z,1513191988.012 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:28.029Z,1513191988.029 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:28.493Z,1513191988.493 [Aanderaa_O2](INFO): Powering down
2017-12-13T19:06:28.615Z,1513191988.615 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:28.616Z,1513191988.616 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-12-13T19:06:46.012Z,1513192006.012 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:06:46.012Z,1513192006.012 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:06:46.012Z,1513192006.012 [BPC1](ERROR): Communications Fault
2017-12-13T19:06:46.186Z,1513192006.186 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:06:47.361Z,1513192007.361 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:06:47.361Z,1513192007.361 [BPC1] No Fault, FailCount= 1
2017-12-13T19:06:52.386Z,1513192012.386 [NAL9602](INFO): Powering up NAL9602
2017-12-13T19:07:03.598Z,1513192023.598 [NAL9602](INFO): NAL9602 initialized
2017-12-13T19:07:08.022Z,1513192028.022 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:07:08.022Z,1513192028.022 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:07:08.022Z,1513192028.022 [BPC1](ERROR): Communications Fault
2017-12-13T19:07:08.082Z,1513192028.082 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:07:09.254Z,1513192029.254 [SBIT](IMPORTANT): Beginning Startup BIT
2017-12-13T19:07:09.305Z,1513192029.305 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:07:09.305Z,1513192029.305 [BPC1] No Fault, FailCount= 2
2017-12-13T19:07:09.306Z,1513192029.306 [CBIT](IMPORTANT): Beginning ground fault scan
2017-12-13T19:07:20.222Z,1513192040.222 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): -0.008812
CHAN A2 (12V): -0.002622
CHAN A3 (5V): -0.001409
CHAN B0 (3.3V): -0.000110
CHAN B1 (3.15aV): -0.000092
CHAN B2 (3.15bV): -0.000449
CHAN B3 (GND): -0.000383
OPEN: -0.000353
Full Scale Calc: 4.765 mA, -1.589 mA
2017-12-13T19:07:36.756Z,1513192056.756 [NAL9602](INFO): SBD MO Status=0, MOMSN=3651, MT Status=0, MTMSN=0
2017-12-13T19:07:36.756Z,1513192056.756 [NAL9602](INFO): No messages in MT queue
2017-12-13T19:07:37.201Z,1513192057.201 [PNI_TCM](ERROR): CRC does not match. Expected:0x19045 got:0x20037
2017-12-13T19:07:43.963Z,1513192063.963 [NAL9602](INFO): GPS fix at 20171213T190729: (36.808071, -121.787626)
2017-12-13T19:07:49.188Z,1513192069.188 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:07:49.188Z,1513192069.188 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:07:49.189Z,1513192069.189 [BPC1](ERROR): Communications Fault
2017-12-13T19:07:49.299Z,1513192069.299 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:07:50.489Z,1513192070.489 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:07:50.489Z,1513192070.489 [BPC1] No Fault, FailCount= 1
2017-12-13T19:08:03.367Z,1513192083.367 [SBIT](IMPORTANT): SBIT PASSED
2017-12-13T19:08:03.745Z,1513192083.745 [MissionManager](IMPORTANT): Started mission Startup
2017-12-13T19:08:03.746Z,1513192083.746 [Startup] Running Loop=1
2017-12-13T19:08:03.746Z,1513192083.746 [Startup](DEBUG): Aggregate::initialize Startup
2017-12-13T19:08:03.746Z,1513192083.746 [Startup:A.GoToSurface] Running Loop=1
2017-12-13T19:08:03.746Z,1513192083.746 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T19:08:03.747Z,1513192083.747 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-13T19:08:03.747Z,1513192083.747 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-13T19:08:03.747Z,1513192083.747 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-13T19:08:03.748Z,1513192083.748 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T19:08:03.748Z,1513192083.748 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T19:08:03.766Z,1513192083.766 [Startup:StartupSatComms] Running Loop=1
2017-12-13T19:08:03.767Z,1513192083.767 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-12-13T19:08:03.767Z,1513192083.767 [Startup:StartupSatComms:A] Running Loop=1
2017-12-13T19:08:04.151Z,1513192084.151 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-12-13T19:08:05.702Z,1513192085.702 [NAL9602](INFO): GPS fix at 20171213T190750: (36.807690, -121.788450)
2017-12-13T19:08:05.758Z,1513192085.758 [Startup:StartupSatComms:A] Stopped
2017-12-13T19:08:05.758Z,1513192085.758 [Startup:StartupSatComms:B] Running Loop=1
2017-12-13T19:08:06.156Z,1513192086.156 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-13T19:08:21.051Z,1513192101.051 [NAL9602](INFO): SBD MO Status=1, MOMSN=3652, MT Status=0, MTMSN=0
2017-12-13T19:08:21.105Z,1513192101.105 [NAL9602](INFO): Sent 25 bytes from file Logs/20171213T172310/Courier0064.lzma
2017-12-13T19:08:21.105Z,1513192101.105 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:08:21.107Z,1513192101.107 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T172310/Courier0064.lzma.parts/0000.sbd
2017-12-13T19:08:21.107Z,1513192101.107 [NAL9602](DEBUG): Completed sending Logs/20171213T172310/Courier0064.lzma
2017-12-13T19:08:30.399Z,1513192110.399 [NAL9602](INFO): SBD MO Status=1, MOMSN=3653, MT Status=0, MTMSN=0
2017-12-13T19:08:30.453Z,1513192110.453 [NAL9602](INFO): Sent 212 bytes from file Logs/20171213T190615/Courier0000.lzma
2017-12-13T19:08:30.453Z,1513192110.453 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:08:30.455Z,1513192110.455 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T190615/Courier0000.lzma.parts/0000.sbd
2017-12-13T19:08:30.455Z,1513192110.455 [NAL9602](DEBUG): Completed sending Logs/20171213T190615/Courier0000.lzma
2017-12-13T19:08:42.919Z,1513192122.919 [NAL9602](INFO): SBD MO Status=1, MOMSN=3654, MT Status=0, MTMSN=0
2017-12-13T19:08:42.973Z,1513192122.973 [NAL9602](INFO): Sent 332 bytes from file Logs/20171213T172310/Express0065.lzma
2017-12-13T19:08:42.973Z,1513192122.973 [NAL9602](INFO): Packets left to send: 1
2017-12-13T19:08:42.975Z,1513192122.975 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T172310/Express0065.lzma.parts/0001.sbd
2017-12-13T19:08:49.834Z,1513192129.834 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:08:49.834Z,1513192129.834 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:08:49.834Z,1513192129.834 [BPC1](ERROR): Communications Fault
2017-12-13T19:08:49.889Z,1513192129.889 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:08:51.089Z,1513192131.089 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:08:51.089Z,1513192131.089 [BPC1] No Fault, FailCount= 1
2017-12-13T19:08:56.611Z,1513192136.611 [NAL9602](INFO): SBD MO Status=1, MOMSN=3655, MT Status=0, MTMSN=0
2017-12-13T19:08:56.661Z,1513192136.661 [NAL9602](INFO): Sent 322 bytes from file Logs/20171213T172310/Express0065.lzma
2017-12-13T19:08:56.661Z,1513192136.661 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:08:56.662Z,1513192136.662 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T172310/Express0065.lzma.parts/0000.sbd
2017-12-13T19:08:56.662Z,1513192136.662 [NAL9602](DEBUG): Completed sending Logs/20171213T172310/Express0065.lzma
2017-12-13T19:09:05.973Z,1513192145.973 [Startup:StartupSatComms:B](INFO): Timed out from 2017-12-13T19:08:05.8Z
2017-12-13T19:09:05.973Z,1513192145.973 [Startup:StartupSatComms:B] Stopped
2017-12-13T19:09:05.974Z,1513192145.974 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-12-13T19:09:05.974Z,1513192145.974 [Startup:StartupSatComms] Stopped
2017-12-13T19:09:05.974Z,1513192145.974 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-12-13T19:09:05.975Z,1513192145.975 [Startup](INFO): Completed Startup
2017-12-13T19:09:05.975Z,1513192145.975 [MissionManager](INFO): Startup is completed.
2017-12-13T19:09:05.975Z,1513192145.975 [MissionManager](INFO): Uninitializing Mission Startup
2017-12-13T19:09:05.975Z,1513192145.975 [Startup] Stopped
2017-12-13T19:09:05.975Z,1513192145.975 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-12-13T19:09:05.975Z,1513192145.975 [Startup:A.GoToSurface] Stopped
2017-12-13T19:09:05.975Z,1513192145.975 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-12-13T19:09:06.391Z,1513192146.391 [MissionManager](IMPORTANT): Started mission Default
2017-12-13T19:09:06.391Z,1513192146.391 [Default] Running Loop=1
2017-12-13T19:09:06.391Z,1513192146.391 [Default](DEBUG): Aggregate::initialize Default
2017-12-13T19:09:06.391Z,1513192146.391 [Default:B.GoToSurface] Running Loop=1
2017-12-13T19:09:06.391Z,1513192146.391 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-12-13T19:09:06.391Z,1513192146.391 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-12-13T19:09:06.392Z,1513192146.392 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-12-13T19:09:06.392Z,1513192146.392 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-12-13T19:09:06.392Z,1513192146.392 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-12-13T19:09:06.397Z,1513192146.397 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-12-13T19:09:06.397Z,1513192146.397 [Default:A.Wait] Running Loop=1
2017-12-13T19:09:06.397Z,1513192146.397 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-12-13T19:09:07.535Z,1513192147.535 [NAL9602](INFO): SBD MO Status=1, MOMSN=3656, MT Status=0, MTMSN=0
2017-12-13T19:09:07.597Z,1513192147.597 [NAL9602](INFO): Sent 332 bytes from file Logs/20171213T190615/Express0001.lzma
2017-12-13T19:09:07.597Z,1513192147.597 [NAL9602](INFO): Packets left to send: 1
2017-12-13T19:09:07.599Z,1513192147.599 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T190615/Express0001.lzma.parts/0001.sbd
2017-12-13T19:09:19.577Z,1513192159.577 [Default:A.Wait](INFO): Done Waiting.
2017-12-13T19:09:19.577Z,1513192159.577 [Default:A.Wait] Stopped
2017-12-13T19:09:19.577Z,1513192159.577 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T19:09:20.003Z,1513192160.003 [Default:CheckIn] Running Loop=1
2017-12-13T19:09:20.003Z,1513192160.003 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T19:09:20.003Z,1513192160.003 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T19:09:20.382Z,1513192160.382 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-12-13T19:09:21.930Z,1513192161.930 [NAL9602](INFO): GPS fix at 20171213T190906: (36.805941, -121.791750)
2017-12-13T19:09:22.041Z,1513192162.041 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T19:09:22.041Z,1513192162.041 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T19:09:22.398Z,1513192162.398 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-12-13T19:09:24.785Z,1513192164.785 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:09:24.785Z,1513192164.785 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T19:09:24.785Z,1513192164.785 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:09:24.810Z,1513192164.810 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:09:25.184Z,1513192165.184 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:09:25.185Z,1513192165.185 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T19:09:25.580Z,1513192165.580 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:09:25.581Z,1513192165.581 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:09:25.581Z,1513192165.581 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:09:44.059Z,1513192184.059 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:09:44.059Z,1513192184.059 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:09:44.059Z,1513192184.059 [BPC1](ERROR): Communications Fault
2017-12-13T19:09:44.135Z,1513192184.135 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:09:45.358Z,1513192185.358 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:09:45.359Z,1513192185.359 [BPC1] No Fault, FailCount= 1
2017-12-13T19:09:51.643Z,1513192191.643 [NAL9602](INFO): SBD MO Status=2, MOMSN=3657, MT Status=2, MTMSN=0
2017-12-13T19:09:51.643Z,1513192191.643 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:10:02.616Z,1513192202.616 [NAL9602](INFO): SBD MO Status=1, MOMSN=3657, MT Status=0, MTMSN=0
2017-12-13T19:10:02.673Z,1513192202.673 [NAL9602](INFO): Sent 63 bytes from file Logs/20171213T190615/Courier0004.lzma
2017-12-13T19:10:02.673Z,1513192202.673 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:10:02.675Z,1513192202.675 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T190615/Courier0004.lzma.parts/0000.sbd
2017-12-13T19:10:02.675Z,1513192202.675 [NAL9602](DEBUG): Completed sending Logs/20171213T190615/Courier0004.lzma
2017-12-13T19:10:05.997Z,1513192205.997 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:10:05.997Z,1513192205.997 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:10:05.997Z,1513192205.997 [BPC1](ERROR): Communications Fault
2017-12-13T19:10:06.056Z,1513192206.056 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:10:07.203Z,1513192207.203 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:10:07.203Z,1513192207.203 [BPC1] No Fault, FailCount= 2
2017-12-13T19:10:17.951Z,1513192217.951 [NAL9602](INFO): SBD MO Status=1, MOMSN=3658, MT Status=0, MTMSN=0
2017-12-13T19:10:18.001Z,1513192218.001 [NAL9602](INFO): Sent 282 bytes from file Logs/20171213T190615/Express0001.lzma
2017-12-13T19:10:18.001Z,1513192218.001 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:10:18.002Z,1513192218.002 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T190615/Express0001.lzma.parts/0000.sbd
2017-12-13T19:10:18.002Z,1513192218.002 [NAL9602](DEBUG): Completed sending Logs/20171213T190615/Express0001.lzma
2017-12-13T19:10:30.815Z,1513192230.815 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004416
2017-12-13T19:10:34.916Z,1513192234.916 [NAL9602](INFO): SBD MO Status=1, MOMSN=3659, MT Status=0, MTMSN=0
2017-12-13T19:10:34.969Z,1513192234.969 [NAL9602](INFO): Sent 109 bytes from file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:10:34.969Z,1513192234.969 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:10:34.971Z,1513192234.971 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T190615/Express0005.lzma.parts/0000.sbd
2017-12-13T19:10:34.971Z,1513192234.971 [NAL9602](DEBUG): Completed sending Logs/20171213T190615/Express0005.lzma
2017-12-13T19:10:35.049Z,1513192235.049 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T19:10:35.049Z,1513192235.049 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T19:10:35.049Z,1513192235.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T19:10:35.127Z,1513192235.127 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:10:35.128Z,1513192235.128 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:10:35.212Z,1513192235.212 [CommandLine](FAULT): Scheduling is paused
2017-12-13T19:10:35.212Z,1513192235.212 [CBIT](INFO): Critical error at 20171213T191035
2017-12-13T19:10:35.212Z,1513192235.212 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-12-13T19:10:39.991Z,1513192239.991 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:10:39.992Z,1513192239.992 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:10:40.067Z,1513192240.067 [CBIT](INFO): Critical error at 20171213T191039
2017-12-13T19:11:05.162Z,1513192265.162 [NAL9602](INFO): Powering down
2017-12-13T19:11:36.789Z,1513192296.789 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:11:36.789Z,1513192296.789 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:11:36.789Z,1513192296.789 [BPC1](ERROR): Communications Fault
2017-12-13T19:11:36.832Z,1513192296.832 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:11:40.583Z,1513192300.583 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:11:40.584Z,1513192300.584 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:11:40.659Z,1513192300.659 [CBIT](INFO): Critical error at 20171213T191140
2017-12-13T19:11:40.661Z,1513192300.661 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:11:40.661Z,1513192300.661 [BPC1] No Fault, FailCount= 1
2017-12-13T19:11:41.386Z,1513192301.386 [DataOverHttps](IMPORTANT): SBD MTMSN=20171213T191127
2017-12-13T19:11:45.836Z,1513192305.836 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:11:45.836Z,1513192305.836 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:11:45.838Z,1513192305.838 [DataOverHttps](INFO): Received command:configSet list
2017-12-13T19:11:45.926Z,1513192305.926 [CBIT](INFO): Critical error at 20171213T191145
2017-12-13T19:11:45.934Z,1513192305.934 [CommandLine](IMPORTANT): got command configSet list
2017-12-13T19:11:45.934Z,1513192305.934 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2017-12-13T19:11:45.935Z,1513192305.935 [CommandLine](IMPORTANT): CBIT.gf24Offset=107 microampere;

2017-12-13T19:11:45.935Z,1513192305.935 [CommandLine](IMPORTANT): CBIT.gfScanTimeout=1 hour;

2017-12-13T19:11:45.935Z,1513192305.935 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2017-12-13T19:11:45.935Z,1513192305.935 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2017-12-13T19:11:45.935Z,1513192305.935 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2017-12-13T19:11:45.936Z,1513192305.935 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_salinity;

2017-12-13T19:11:45.936Z,1513192305.936 [CommandLine](IMPORTANT): Normal all CTD_Seabird.sea_water_temperature;

2017-12-13T19:11:45.936Z,1513192305.936 [CommandLine](IMPORTANT): Normal linearApproximation Rowe_600LCM.height_above_sea_floor 5.000000 meter;

2017-12-13T19:11:45.936Z,1513192305.936 [CommandLine](IMPORTANT): Normal all mass_concentration_of_chlorophyll_in_sea_water;

2017-12-13T19:11:45.936Z,1513192305.936 [CommandLine](IMPORTANT): Normal all sea_water_temperature;

2017-12-13T19:11:45.936Z,1513192305.936 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2017-12-13T19:11:45.936Z,1513192305.936 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=470 milliliter;

2017-12-13T19:11:45.936Z,1513192305.936 [CommandLine](IMPORTANT): VerticalControl.massDefault=-6 millimeter;

2017-12-13T19:12:25.885Z,1513192345.885 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:12:25.886Z,1513192345.886 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2017-12-13T19:12:25.886Z,1513192345.886 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:12:25.906Z,1513192345.906 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:12:26.293Z,1513192346.293 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:12:26.293Z,1513192346.293 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2017-12-13T19:12:26.688Z,1513192346.688 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:12:26.688Z,1513192346.688 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:12:26.689Z,1513192346.689 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:12:32.661Z,1513192352.661 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:12:32.661Z,1513192352.661 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:12:32.661Z,1513192352.661 [BPC1](ERROR): Communications Fault
2017-12-13T19:12:32.683Z,1513192352.683 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:12:33.884Z,1513192353.884 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:12:33.884Z,1513192353.884 [BPC1] No Fault, FailCount= 1
2017-12-13T19:12:45.627Z,1513192365.627 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:12:45.628Z,1513192365.628 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:12:45.715Z,1513192365.715 [CBIT](INFO): Critical error at 20171213T191245
2017-12-13T19:12:54.789Z,1513192374.789 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:12:54.789Z,1513192374.789 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:12:54.789Z,1513192374.789 [BPC1](ERROR): Communications Fault
2017-12-13T19:12:54.886Z,1513192374.886 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:12:56.011Z,1513192376.011 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:12:56.011Z,1513192376.011 [BPC1] No Fault, FailCount= 2
2017-12-13T19:13:35.592Z,1513192415.592 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:13:35.592Z,1513192415.592 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:13:35.592Z,1513192415.592 [BPC1](ERROR): Communications Fault
2017-12-13T19:13:35.613Z,1513192415.613 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:13:36.812Z,1513192416.812 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:13:36.812Z,1513192416.812 [BPC1] No Fault, FailCount= 1
2017-12-13T19:13:45.851Z,1513192425.851 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:13:45.852Z,1513192425.852 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:13:45.921Z,1513192425.921 [CBIT](INFO): Critical error at 20171213T191345
2017-12-13T19:14:29.445Z,1513192469.445 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:14:29.446Z,1513192469.446 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:14:29.446Z,1513192469.446 [BPC1](ERROR): Communications Fault
2017-12-13T19:14:29.506Z,1513192469.506 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:14:30.669Z,1513192470.669 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:14:30.669Z,1513192470.669 [BPC1] No Fault, FailCount= 1
2017-12-13T19:14:37.064Z,1513192477.064 [PNI_TCM](ERROR): CRC does not match. Expected:0x1559 got:0x58648
2017-12-13T19:14:45.939Z,1513192485.939 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:14:45.940Z,1513192485.940 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:14:46.039Z,1513192486.039 [CBIT](INFO): Critical error at 20171213T191445
2017-12-13T19:15:23.513Z,1513192523.513 [PNI_TCM](ERROR): CRC does not match. Expected:0x14375 got:0x49501
2017-12-13T19:15:25.117Z,1513192525.117 [PNI_TCM](ERROR): CRC does not match. Expected:0x10555 got:0x27530
2017-12-13T19:15:26.755Z,1513192526.755 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:15:26.755Z,1513192526.755 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2017-12-13T19:15:26.755Z,1513192526.755 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:15:26.768Z,1513192526.768 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:15:27.162Z,1513192527.162 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:15:27.162Z,1513192527.162 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2017-12-13T19:15:27.546Z,1513192527.546 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:15:27.546Z,1513192527.546 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:15:27.547Z,1513192527.547 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:15:28.723Z,1513192528.723 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:15:28.723Z,1513192528.723 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:15:28.723Z,1513192528.723 [BPC1](ERROR): Communications Fault
2017-12-13T19:15:28.780Z,1513192528.780 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:15:29.925Z,1513192529.925 [PNI_TCM](ERROR): CRC does not match. Expected:0x30154 got:0x8113
2017-12-13T19:15:29.969Z,1513192529.969 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:15:29.969Z,1513192529.969 [BPC1] No Fault, FailCount= 1
2017-12-13T19:15:31.530Z,1513192531.530 [PNI_TCM](ERROR): CRC does not match. Expected:0x22361 got:0x7
2017-12-13T19:15:35.571Z,1513192535.571 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T19:15:35.572Z,1513192535.572 [Default:CheckIn:C.Wait] Stopped
2017-12-13T19:15:35.572Z,1513192535.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T19:15:35.572Z,1513192535.572 [Default:CheckIn:D] Running Loop=1
2017-12-13T19:15:35.957Z,1513192535.957 [Default:CheckIn:D] Stopped
2017-12-13T19:15:35.957Z,1513192535.957 [Default:CheckIn:E] Running Loop=1
2017-12-13T19:15:36.355Z,1513192536.355 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.492731 min 
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn:E] Stopped
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn] Stopped
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn](INFO): Running loop #2
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn] Running Loop=2
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T19:15:36.377Z,1513192536.377 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T19:15:37.098Z,1513192537.098 [NAL9602](INFO): Powering up
2017-12-13T19:15:46.011Z,1513192546.011 [DataOverHttps](IMPORTANT): Could not stat file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:15:46.011Z,1513192546.011 [DataOverHttps](CRITICAL): Could not open file Logs/20171213T190615/Express0005.lzma
2017-12-13T19:15:46.080Z,1513192546.080 [CBIT](INFO): Critical error at 20171213T191546
2017-12-13T19:15:47.950Z,1513192547.950 [NAL9602](INFO): NAL9602 initialized
2017-12-13T19:16:01.986Z,1513192561.986 [PNI_TCM](ERROR): CRC does not match. Expected:0x4540 got:0x16807
2017-12-13T19:16:05.151Z,1513192565.151 [NAL9602](INFO): SBD MO Status=0, MOMSN=3660, MT Status=0, MTMSN=0
2017-12-13T19:16:05.151Z,1513192565.151 [NAL9602](INFO): No messages in MT queue
2017-12-13T19:16:05.177Z,1513192565.177 [PNI_TCM](ERROR): CRC does not match. Expected:0x51766 got:0x2948
2017-12-13T19:16:08.406Z,1513192568.406 [PNI_TCM](ERROR): CRC does not match. Expected:0x64690 got:0x6593
2017-12-13T19:16:16.790Z,1513192576.790 [PNI_TCM](ERROR): CRC does not match. Expected:0x54487 got:0x7105
2017-12-13T19:16:17.954Z,1513192577.954 [NAL9602](INFO): GPS fix at 20171213T191602: (36.792271, -121.838116)
2017-12-13T19:16:18.024Z,1513192578.024 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T19:16:18.024Z,1513192578.024 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T19:16:18.401Z,1513192578.401 [PNI_TCM](ERROR): CRC does not match. Expected:0x16096 got:0x55608
2017-12-13T19:16:22.519Z,1513192582.519 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:16:22.519Z,1513192582.519 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:16:22.519Z,1513192582.519 [BPC1](ERROR): Communications Fault
2017-12-13T19:16:22.540Z,1513192582.540 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:16:22.629Z,1513192582.629 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20171213T190615/Courier0008.lzma
2017-12-13T19:16:23.387Z,1513192583.387 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Courier0008.lzma.bak
2017-12-13T19:16:23.387Z,1513192583.387 [DataOverHttps](INFO): SBD MOMSN=5435873
2017-12-13T19:16:23.481Z,1513192583.481 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:16:23.481Z,1513192583.481 [BPC1] No Fault, FailCount= 1
2017-12-13T19:16:27.587Z,1513192587.587 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T19:16:32.537Z,1513192592.537 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T190615/Courier0012.lzma
2017-12-13T19:16:33.287Z,1513192593.287 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Courier0012.lzma.bak
2017-12-13T19:16:33.287Z,1513192593.287 [DataOverHttps](INFO): SBD MOMSN=5435884
2017-12-13T19:16:42.443Z,1513192602.443 [DataOverHttps](INFO): Sending 730 bytes from file Logs/20171213T190615/Express0009.lzma
2017-12-13T19:16:43.163Z,1513192603.163 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Express0009.lzma.bak
2017-12-13T19:16:43.163Z,1513192603.163 [DataOverHttps](INFO): SBD MOMSN=5435886
2017-12-13T19:16:47.199Z,1513192607.199 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:16:47.200Z,1513192607.200 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:16:47.200Z,1513192607.200 [BPC1](ERROR): Communications Fault
2017-12-13T19:16:47.262Z,1513192607.262 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:16:48.173Z,1513192608.173 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:16:48.173Z,1513192608.173 [BPC1] No Fault, FailCount= 2
2017-12-13T19:16:52.606Z,1513192612.606 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T190615/Express0013.lzma
2017-12-13T19:16:52.954Z,1513192612.954 [NAL9602](INFO): Powering down
2017-12-13T19:16:53.359Z,1513192613.359 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Express0013.lzma.bak
2017-12-13T19:16:53.359Z,1513192613.359 [DataOverHttps](INFO): SBD MOMSN=5435914
2017-12-13T19:16:54.071Z,1513192614.071 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T19:16:54.071Z,1513192614.071 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T19:16:54.071Z,1513192614.071 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T19:17:28.269Z,1513192648.269 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:17:28.270Z,1513192648.270 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:17:28.270Z,1513192648.270 [BPC1](ERROR): Communications Fault
2017-12-13T19:17:28.318Z,1513192648.318 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:17:29.530Z,1513192649.530 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:17:29.530Z,1513192649.530 [BPC1] No Fault, FailCount= 1
2017-12-13T19:18:08.728Z,1513192688.728 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:18:08.728Z,1513192688.728 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:18:08.733Z,1513192688.733 [BPC1](ERROR): Communications Fault
2017-12-13T19:18:08.783Z,1513192688.783 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:18:09.981Z,1513192689.981 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:18:09.981Z,1513192689.981 [BPC1] No Fault, FailCount= 1
2017-12-13T19:18:27.562Z,1513192707.562 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:18:27.562Z,1513192707.562 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T19:18:27.562Z,1513192707.562 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:18:27.592Z,1513192707.592 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:18:27.975Z,1513192707.975 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:18:27.975Z,1513192707.975 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T19:18:28.362Z,1513192708.362 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:18:28.362Z,1513192708.362 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:18:28.362Z,1513192708.362 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:18:30.734Z,1513192710.734 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:18:30.734Z,1513192710.734 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:18:30.734Z,1513192710.734 [BPC1](ERROR): Communications Fault
2017-12-13T19:18:30.809Z,1513192710.809 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:18:31.982Z,1513192711.982 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:18:31.982Z,1513192711.982 [BPC1] No Fault, FailCount= 2
2017-12-13T19:19:13.243Z,1513192753.243 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:19:13.243Z,1513192753.243 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:19:13.243Z,1513192753.243 [BPC1](ERROR): Communications Fault
2017-12-13T19:19:13.301Z,1513192753.301 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:19:14.493Z,1513192754.493 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:19:14.493Z,1513192754.493 [BPC1] No Fault, FailCount= 1
2017-12-13T19:20:06.639Z,1513192806.639 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:20:06.639Z,1513192806.639 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:20:06.639Z,1513192806.639 [BPC1](ERROR): Communications Fault
2017-12-13T19:20:06.682Z,1513192806.682 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:20:07.884Z,1513192807.884 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:20:07.889Z,1513192807.889 [BPC1] No Fault, FailCount= 1
2017-12-13T19:21:00.999Z,1513192860.999 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:21:00.999Z,1513192860.999 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:21:00.999Z,1513192860.999 [BPC1](ERROR): Communications Fault
2017-12-13T19:21:01.065Z,1513192861.065 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:21:02.231Z,1513192862.231 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:21:02.231Z,1513192862.231 [BPC1] No Fault, FailCount= 1
2017-12-13T19:21:28.633Z,1513192888.633 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:21:28.633Z,1513192888.633 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2017-12-13T19:21:28.633Z,1513192888.633 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:21:28.654Z,1513192888.654 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:21:29.039Z,1513192889.039 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:21:29.039Z,1513192889.039 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2017-12-13T19:21:29.430Z,1513192889.430 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:21:29.431Z,1513192889.431 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:21:29.431Z,1513192889.431 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:21:54.236Z,1513192914.236 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T19:21:54.236Z,1513192914.236 [Default:CheckIn:C.Wait] Stopped
2017-12-13T19:21:54.236Z,1513192914.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T19:21:54.236Z,1513192914.236 [Default:CheckIn:D] Running Loop=1
2017-12-13T19:21:54.631Z,1513192914.631 [Default:CheckIn:D] Stopped
2017-12-13T19:21:54.631Z,1513192914.631 [Default:CheckIn:E] Running Loop=1
2017-12-13T19:21:55.032Z,1513192915.032 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.803997 min 
2017-12-13T19:21:55.032Z,1513192915.032 [Default:CheckIn:E] Stopped
2017-12-13T19:21:55.032Z,1513192915.032 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T19:21:55.032Z,1513192915.032 [Default:CheckIn] Stopped
2017-12-13T19:21:55.032Z,1513192915.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T19:21:55.032Z,1513192915.032 [Default:CheckIn](INFO): Running loop #3
2017-12-13T19:21:55.033Z,1513192915.033 [Default:CheckIn] Running Loop=3
2017-12-13T19:21:55.033Z,1513192915.033 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T19:21:55.033Z,1513192915.033 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T19:21:58.584Z,1513192918.584 [NAL9602](INFO): Powering up
2017-12-13T19:21:59.954Z,1513192919.954 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:21:59.954Z,1513192919.954 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:21:59.954Z,1513192919.954 [BPC1](ERROR): Communications Fault
2017-12-13T19:22:00.006Z,1513192920.006 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:22:01.207Z,1513192921.207 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:22:01.207Z,1513192921.207 [BPC1] No Fault, FailCount= 1
2017-12-13T19:22:09.530Z,1513192929.530 [NAL9602](INFO): NAL9602 initialized
2017-12-13T19:22:42.335Z,1513192962.335 [NAL9602](INFO): SBD MO Status=2, MOMSN=3661, MT Status=2, MTMSN=0
2017-12-13T19:22:42.335Z,1513192962.335 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:22:52.763Z,1513192972.763 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:22:52.763Z,1513192972.763 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:22:52.763Z,1513192972.763 [BPC1](ERROR): Communications Fault
2017-12-13T19:22:52.806Z,1513192972.806 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:22:53.993Z,1513192973.993 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:22:53.993Z,1513192973.993 [BPC1] No Fault, FailCount= 1
2017-12-13T19:23:01.231Z,1513192981.231 [NAL9602](INFO): SBD MO Status=2, MOMSN=3661, MT Status=2, MTMSN=0
2017-12-13T19:23:01.232Z,1513192981.232 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:23:14.860Z,1513192994.860 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:23:14.860Z,1513192994.860 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:23:14.860Z,1513192994.860 [BPC1](ERROR): Communications Fault
2017-12-13T19:23:14.910Z,1513192994.910 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:23:16.077Z,1513192996.077 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:23:16.077Z,1513192996.077 [BPC1] No Fault, FailCount= 2
2017-12-13T19:23:18.435Z,1513192998.435 [NAL9602](INFO): SBD MO Status=0, MOMSN=3661, MT Status=0, MTMSN=0
2017-12-13T19:23:18.435Z,1513192998.435 [NAL9602](INFO): No messages in MT queue
2017-12-13T19:23:19.623Z,1513192999.623 [NAL9602](INFO): GPS fix at 20171213T192303: (36.779021, -121.888505)
2017-12-13T19:23:19.696Z,1513192999.696 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T19:23:19.696Z,1513192999.696 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T19:23:24.356Z,1513193004.356 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20171213T190615/Courier0016.lzma
2017-12-13T19:23:25.108Z,1513193005.108 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Courier0016.lzma.bak
2017-12-13T19:23:25.108Z,1513193005.108 [DataOverHttps](INFO): SBD MOMSN=5435924
2017-12-13T19:23:36.185Z,1513193016.185 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20171213T190615/Express0017.lzma
2017-12-13T19:23:36.959Z,1513193016.959 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Express0017.lzma.bak
2017-12-13T19:23:36.959Z,1513193016.959 [DataOverHttps](INFO): SBD MOMSN=5435927
2017-12-13T19:23:37.610Z,1513193017.610 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T19:23:37.610Z,1513193017.610 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T19:23:37.611Z,1513193017.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T19:23:53.134Z,1513193033.134 [PNI_TCM](ERROR): CRC does not match. Expected:0x53214 got:0x49525
2017-12-13T19:23:54.702Z,1513193034.702 [NAL9602](INFO): Powering down
2017-12-13T19:23:54.729Z,1513193034.729 [PNI_TCM](ERROR): CRC does not match. Expected:0x58014 got:0x47923
2017-12-13T19:23:57.923Z,1513193037.923 [PNI_TCM](ERROR): CRC does not match. Expected:0x33066 got:0x27841
2017-12-13T19:24:01.513Z,1513193041.513 [PNI_TCM](ERROR): CRC does not match. Expected:0x15720 got:0x55064
2017-12-13T19:24:29.549Z,1513193069.549 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:24:29.549Z,1513193069.549 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T19:24:29.549Z,1513193069.549 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:24:29.575Z,1513193069.575 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:24:29.976Z,1513193069.976 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:24:29.976Z,1513193069.976 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T19:24:30.326Z,1513193070.326 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:24:30.326Z,1513193070.326 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:24:30.327Z,1513193070.327 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:24:45.990Z,1513193085.990 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41
2017-12-13T19:24:47.987Z,1513193087.987 [PNI_TCM](ERROR): CRC does not match. Expected:0x61927 got:0x1797
2017-12-13T19:24:49.589Z,1513193089.589 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:24:49.589Z,1513193089.589 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:24:49.589Z,1513193089.589 [BPC1](ERROR): Communications Fault
2017-12-13T19:24:49.640Z,1513193089.640 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:24:50.835Z,1513193090.835 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:24:50.835Z,1513193090.835 [BPC1] No Fault, FailCount= 1
2017-12-13T19:25:42.123Z,1513193142.123 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:25:42.124Z,1513193142.124 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:25:42.124Z,1513193142.124 [BPC1](ERROR): Communications Fault
2017-12-13T19:25:42.145Z,1513193142.145 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:25:43.352Z,1513193143.352 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:25:43.352Z,1513193143.352 [BPC1] No Fault, FailCount= 1
2017-12-13T19:26:10.919Z,1513193170.919 [PNI_TCM](ERROR): CRC does not match. Expected:0x45656 got:0x32130
2017-12-13T19:26:11.322Z,1513193171.322 [PNI_TCM](ERROR): CRC does not match. Expected:0x54264 got:0x49566
2017-12-13T19:26:14.933Z,1513193174.933 [PNI_TCM](ERROR): CRC does not match. Expected:0x4207 got:0x9469
2017-12-13T19:26:18.135Z,1513193178.135 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T19:26:18.135Z,1513193178.135 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:26:18.135Z,1513193178.135 [BPC1](ERROR): Data Fault
2017-12-13T19:26:18.158Z,1513193178.158 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:26:19.370Z,1513193179.370 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:26:19.370Z,1513193179.370 [BPC1] No Fault, FailCount= 1
2017-12-13T19:26:27.781Z,1513193187.781 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T19:26:42.541Z,1513193202.541 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:26:42.541Z,1513193202.541 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:26:42.542Z,1513193202.542 [BPC1](ERROR): Communications Fault
2017-12-13T19:26:42.599Z,1513193202.599 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:26:43.498Z,1513193203.498 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:26:43.498Z,1513193203.498 [BPC1] No Fault, FailCount= 2
2017-12-13T19:27:21.439Z,1513193241.439 [PNI_TCM](ERROR): CRC does not match. Expected:0x3290 got:0x6208
2017-12-13T19:27:23.040Z,1513193243.040 [PNI_TCM](ERROR): CRC does not match. Expected:0x42255 got:0x49240
2017-12-13T19:27:27.849Z,1513193247.849 [PNI_TCM](ERROR): CRC does not match. Expected:0x9130 got:0x6208
2017-12-13T19:27:30.667Z,1513193250.667 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:27:30.667Z,1513193250.667 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2017-12-13T19:27:30.667Z,1513193250.667 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:27:30.680Z,1513193250.680 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:27:31.084Z,1513193251.084 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:27:31.084Z,1513193251.084 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2017-12-13T19:27:31.468Z,1513193251.468 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:27:31.469Z,1513193251.469 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:27:31.469Z,1513193251.469 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:27:37.858Z,1513193257.858 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:27:37.858Z,1513193257.858 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:27:37.859Z,1513193257.859 [BPC1](ERROR): Communications Fault
2017-12-13T19:27:37.892Z,1513193257.892 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:27:42.015Z,1513193262.015 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:27:42.015Z,1513193262.015 [BPC1] No Fault, FailCount= 1
2017-12-13T19:28:36.070Z,1513193316.070 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:28:36.070Z,1513193316.070 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:28:36.070Z,1513193316.070 [BPC1](ERROR): Communications Fault
2017-12-13T19:28:36.138Z,1513193316.138 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:28:37.311Z,1513193317.311 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:28:37.311Z,1513193317.311 [BPC1] No Fault, FailCount= 1
2017-12-13T19:28:38.091Z,1513193318.091 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T19:28:38.091Z,1513193318.091 [Default:CheckIn:C.Wait] Stopped
2017-12-13T19:28:38.091Z,1513193318.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T19:28:38.091Z,1513193318.091 [Default:CheckIn:D] Running Loop=1
2017-12-13T19:28:38.506Z,1513193318.506 [Default:CheckIn:D] Stopped
2017-12-13T19:28:38.506Z,1513193318.506 [Default:CheckIn:E] Running Loop=1
2017-12-13T19:28:38.887Z,1513193318.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.535254 min 
2017-12-13T19:28:38.887Z,1513193318.887 [Default:CheckIn:E] Stopped
2017-12-13T19:28:38.887Z,1513193318.887 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T19:28:38.887Z,1513193318.887 [Default:CheckIn] Stopped
2017-12-13T19:28:38.888Z,1513193318.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T19:28:38.888Z,1513193318.888 [Default:CheckIn](INFO): Running loop #4
2017-12-13T19:28:38.888Z,1513193318.888 [Default:CheckIn] Running Loop=4
2017-12-13T19:28:38.888Z,1513193318.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T19:28:38.888Z,1513193318.888 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T19:28:39.650Z,1513193319.650 [NAL9602](INFO): Powering up
2017-12-13T19:28:50.610Z,1513193330.610 [NAL9602](INFO): NAL9602 initialized
2017-12-13T19:29:17.815Z,1513193357.815 [NAL9602](INFO): SBD MO Status=2, MOMSN=3662, MT Status=2, MTMSN=0
2017-12-13T19:29:17.815Z,1513193357.815 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:29:28.247Z,1513193368.247 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T19:29:28.247Z,1513193368.247 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:29:28.247Z,1513193368.247 [BPC1](ERROR): Data Fault
2017-12-13T19:29:28.294Z,1513193368.294 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:29:29.493Z,1513193369.493 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:29:29.493Z,1513193369.493 [BPC1] No Fault, FailCount= 1
2017-12-13T19:29:34.611Z,1513193374.611 [NAL9602](INFO): SBD MO Status=2, MOMSN=3662, MT Status=2, MTMSN=0
2017-12-13T19:29:34.612Z,1513193374.612 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:29:45.635Z,1513193385.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=3662, MT Status=0, MTMSN=0
2017-12-13T19:29:45.635Z,1513193385.635 [NAL9602](INFO): No messages in MT queue
2017-12-13T19:29:46.073Z,1513193386.073 [PNI_TCM](ERROR): CRC does not match. Expected:0x58430 got:0x51975
2017-12-13T19:29:46.085Z,1513193386.085 [BPC1](ERROR): Failed to parse message.
2017-12-13T19:29:46.085Z,1513193386.085 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T19:29:46.086Z,1513193386.086 [BPC1] Data Fault, FailCount= 2
2017-12-13T19:29:46.086Z,1513193386.086 [BPC1](ERROR): Data Fault
2017-12-13T19:29:46.134Z,1513193386.134 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:29:46.861Z,1513193386.861 [NAL9602](INFO): GPS fix at 20171213T192929: (36.769786, -121.932955)
2017-12-13T19:29:46.938Z,1513193386.938 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T19:29:46.938Z,1513193386.938 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T19:29:47.317Z,1513193387.317 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:29:47.317Z,1513193387.317 [BPC1] No Fault, FailCount= 2
2017-12-13T19:29:51.445Z,1513193391.445 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20171213T190615/Courier0020.lzma
2017-12-13T19:29:52.187Z,1513193392.187 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Courier0020.lzma.bak
2017-12-13T19:29:52.188Z,1513193392.188 [DataOverHttps](INFO): SBD MOMSN=5435938
2017-12-13T19:30:01.865Z,1513193401.865 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20171213T190615/Express0021.lzma
2017-12-13T19:30:02.595Z,1513193402.595 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Express0021.lzma.bak
2017-12-13T19:30:02.595Z,1513193402.595 [DataOverHttps](INFO): SBD MOMSN=5435941
2017-12-13T19:30:03.240Z,1513193403.240 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T19:30:03.240Z,1513193403.240 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T19:30:03.240Z,1513193403.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T19:30:21.563Z,1513193421.563 [NAL9602](INFO): Powering down
2017-12-13T19:30:29.182Z,1513193429.182 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:30:29.183Z,1513193429.183 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:30:29.183Z,1513193429.183 [BPC1](ERROR): Communications Fault
2017-12-13T19:30:29.242Z,1513193429.242 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:30:30.433Z,1513193430.433 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:30:30.433Z,1513193430.433 [BPC1] No Fault, FailCount= 1
2017-12-13T19:30:31.605Z,1513193431.605 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:30:31.605Z,1513193431.605 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T19:30:31.605Z,1513193431.605 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:30:31.629Z,1513193431.629 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:30:32.037Z,1513193432.037 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:30:32.038Z,1513193432.038 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T19:30:32.411Z,1513193432.411 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:30:32.411Z,1513193432.411 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:30:32.412Z,1513193432.412 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:31:24.467Z,1513193484.467 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:31:24.467Z,1513193484.467 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:31:24.467Z,1513193484.467 [BPC1](ERROR): Communications Fault
2017-12-13T19:31:24.488Z,1513193484.488 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:31:25.690Z,1513193485.690 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:31:25.690Z,1513193485.690 [BPC1] No Fault, FailCount= 1
2017-12-13T19:31:54.870Z,1513193514.870 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T19:31:54.870Z,1513193514.870 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:31:54.871Z,1513193514.871 [BPC1](ERROR): Data Fault
2017-12-13T19:31:54.893Z,1513193514.893 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:31:56.096Z,1513193516.096 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:31:56.096Z,1513193516.096 [BPC1] No Fault, FailCount= 1
2017-12-13T19:32:17.109Z,1513193537.109 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:32:17.109Z,1513193537.109 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:32:17.109Z,1513193537.109 [BPC1](ERROR): Communications Fault
2017-12-13T19:32:17.131Z,1513193537.131 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:32:18.340Z,1513193538.340 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:32:18.340Z,1513193538.340 [BPC1] No Fault, FailCount= 2
2017-12-13T19:32:18.704Z,1513193538.704 [PNI_TCM](ERROR): CRC does not match. Expected:0x49490 got:0x42432
2017-12-13T19:33:18.834Z,1513193598.834 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:33:18.834Z,1513193598.834 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:33:18.834Z,1513193598.834 [BPC1](ERROR): Communications Fault
2017-12-13T19:33:18.886Z,1513193598.886 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:33:20.068Z,1513193600.068 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:33:20.068Z,1513193600.068 [BPC1] No Fault, FailCount= 1
2017-12-13T19:33:32.462Z,1513193612.462 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:33:32.462Z,1513193612.462 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2017-12-13T19:33:32.462Z,1513193612.462 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:33:32.474Z,1513193612.474 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:33:32.876Z,1513193612.876 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:33:32.876Z,1513193612.876 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2017-12-13T19:33:33.226Z,1513193613.226 [PNI_TCM](ERROR): CRC does not match. Expected:0x46391 got:0x6464
2017-12-13T19:33:33.272Z,1513193613.272 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:33:33.273Z,1513193613.273 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:33:33.273Z,1513193613.273 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:33:34.828Z,1513193614.828 [PNI_TCM](ERROR): CRC does not match. Expected:0x7242 got:0x49428
2017-12-13T19:33:38.038Z,1513193618.038 [PNI_TCM](ERROR): CRC does not match. Expected:0x53317 got:0x21532
2017-12-13T19:33:39.634Z,1513193619.634 [PNI_TCM](ERROR): CRC does not match. Expected:0x5421 got:0x10780
2017-12-13T19:33:41.234Z,1513193621.234 [PNI_TCM](ERROR): CRC does not match. Expected:0x10901 got:0x1857
2017-12-13T19:33:42.832Z,1513193622.832 [PNI_TCM](ERROR): CRC does not match. Expected:0x27981 got:0x16146
2017-12-13T19:33:43.232Z,1513193623.232 [PNI_TCM](ERROR): CRC does not match. Expected:0x19649 got:0x32440
2017-12-13T19:34:12.528Z,1513193652.528 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:34:12.528Z,1513193652.528 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:34:12.528Z,1513193652.528 [BPC1](ERROR): Communications Fault
2017-12-13T19:34:12.575Z,1513193652.575 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:34:13.762Z,1513193653.762 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:34:13.762Z,1513193653.762 [BPC1] No Fault, FailCount= 1
2017-12-13T19:34:37.328Z,1513193677.328 [PNI_TCM](ERROR): CRC does not match. Expected:0x64065 got:0x3755
2017-12-13T19:34:38.927Z,1513193678.927 [PNI_TCM](ERROR): CRC does not match. Expected:0x11225 got:0x7361
2017-12-13T19:34:42.135Z,1513193682.135 [PNI_TCM](ERROR): CRC does not match. Expected:0x2371 got:0x16805
2017-12-13T19:34:43.750Z,1513193683.750 [PNI_TCM](ERROR): CRC does not match. Expected:0x34361 got:0x17974
2017-12-13T19:35:03.749Z,1513193703.749 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T19:35:03.749Z,1513193703.749 [Default:CheckIn:C.Wait] Stopped
2017-12-13T19:35:03.749Z,1513193703.749 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T19:35:03.749Z,1513193703.749 [Default:CheckIn:D] Running Loop=1
2017-12-13T19:35:04.163Z,1513193704.163 [Default:CheckIn:D] Stopped
2017-12-13T19:35:04.163Z,1513193704.163 [Default:CheckIn:E] Running Loop=1
2017-12-13T19:35:04.562Z,1513193704.562 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.962870 min 
2017-12-13T19:35:04.562Z,1513193704.562 [Default:CheckIn:E] Stopped
2017-12-13T19:35:04.562Z,1513193704.562 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T19:35:04.562Z,1513193704.562 [Default:CheckIn] Stopped
2017-12-13T19:35:04.563Z,1513193704.563 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T19:35:04.563Z,1513193704.563 [Default:CheckIn](INFO): Running loop #5
2017-12-13T19:35:04.563Z,1513193704.563 [Default:CheckIn] Running Loop=5
2017-12-13T19:35:04.563Z,1513193704.563 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T19:35:04.563Z,1513193704.563 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T19:35:08.135Z,1513193708.135 [NAL9602](INFO): Powering up
2017-12-13T19:35:08.162Z,1513193708.162 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:35:08.162Z,1513193708.162 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:35:08.162Z,1513193708.162 [BPC1](ERROR): Communications Fault
2017-12-13T19:35:08.209Z,1513193708.209 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:35:09.079Z,1513193709.079 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:35:09.079Z,1513193709.079 [BPC1] No Fault, FailCount= 1
2017-12-13T19:35:19.022Z,1513193719.022 [NAL9602](INFO): NAL9602 initialized
2017-12-13T19:35:29.866Z,1513193729.866 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:35:29.867Z,1513193729.867 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:35:29.867Z,1513193729.867 [BPC1](ERROR): Communications Fault
2017-12-13T19:35:29.929Z,1513193729.929 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:35:31.109Z,1513193731.109 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:35:31.109Z,1513193731.109 [BPC1] No Fault, FailCount= 2
2017-12-13T19:35:35.827Z,1513193735.827 [NAL9602](INFO): SBD MO Status=0, MOMSN=3663, MT Status=0, MTMSN=0
2017-12-13T19:35:35.827Z,1513193735.827 [NAL9602](INFO): No messages in MT queue
2017-12-13T19:35:47.842Z,1513193747.842 [NAL9602](INFO): GPS fix at 20171213T193530: (36.764784, -121.976385)
2017-12-13T19:35:47.912Z,1513193747.912 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T19:35:47.912Z,1513193747.912 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T19:35:53.145Z,1513193753.145 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20171213T190615/Courier0024.lzma
2017-12-13T19:35:55.139Z,1513193755.139 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Courier0024.lzma.bak
2017-12-13T19:35:55.139Z,1513193755.139 [DataOverHttps](INFO): SBD MOMSN=5435952
2017-12-13T19:36:07.809Z,1513193767.809 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20171213T190615/Express0025.lzma
2017-12-13T19:36:08.607Z,1513193768.607 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Express0025.lzma.bak
2017-12-13T19:36:08.608Z,1513193768.608 [DataOverHttps](INFO): SBD MOMSN=5435957
2017-12-13T19:36:09.261Z,1513193769.261 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T19:36:09.261Z,1513193769.261 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T19:36:09.261Z,1513193769.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T19:36:22.842Z,1513193782.842 [NAL9602](INFO): Powering down
2017-12-13T19:36:28.099Z,1513193788.099 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T19:36:31.281Z,1513193791.281 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:36:31.282Z,1513193791.282 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:36:31.282Z,1513193791.282 [BPC1](ERROR): Communications Fault
2017-12-13T19:36:31.323Z,1513193791.323 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:36:32.494Z,1513193792.494 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:36:32.494Z,1513193792.494 [BPC1] No Fault, FailCount= 1
2017-12-13T19:36:33.291Z,1513193793.291 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:36:33.292Z,1513193793.292 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T19:36:33.292Z,1513193793.292 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:36:33.304Z,1513193793.304 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:36:33.711Z,1513193793.711 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:36:33.711Z,1513193793.711 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T19:36:34.086Z,1513193794.086 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:36:34.087Z,1513193794.087 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:36:34.087Z,1513193794.087 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:36:53.281Z,1513193813.281 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:36:53.281Z,1513193813.281 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:36:53.281Z,1513193813.281 [BPC1](ERROR): Communications Fault
2017-12-13T19:36:53.309Z,1513193813.309 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:36:54.510Z,1513193814.510 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:36:54.510Z,1513193814.510 [BPC1] No Fault, FailCount= 2
2017-12-13T19:37:35.057Z,1513193855.057 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:37:35.057Z,1513193855.057 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:37:35.057Z,1513193855.057 [BPC1](ERROR): Communications Fault
2017-12-13T19:37:35.119Z,1513193855.119 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:37:36.315Z,1513193856.315 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:37:36.315Z,1513193856.315 [BPC1] No Fault, FailCount= 1
2017-12-13T19:38:35.230Z,1513193915.230 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:38:35.230Z,1513193915.230 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:38:35.230Z,1513193915.230 [BPC1](ERROR): Communications Fault
2017-12-13T19:38:35.251Z,1513193915.251 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:38:36.449Z,1513193916.449 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:38:36.449Z,1513193916.449 [BPC1] No Fault, FailCount= 1
2017-12-13T19:39:05.625Z,1513193945.625 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T19:39:05.625Z,1513193945.625 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:39:05.625Z,1513193945.625 [BPC1](ERROR): Data Fault
2017-12-13T19:39:05.673Z,1513193945.673 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:39:06.858Z,1513193946.858 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:39:06.858Z,1513193946.858 [BPC1] No Fault, FailCount= 1
2017-12-13T19:39:28.550Z,1513193968.550 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:39:28.551Z,1513193968.551 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:39:28.551Z,1513193968.551 [BPC1](ERROR): Communications Fault
2017-12-13T19:39:28.619Z,1513193968.619 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:39:29.805Z,1513193969.805 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:39:29.805Z,1513193969.805 [BPC1] No Fault, FailCount= 2
2017-12-13T19:39:34.180Z,1513193974.180 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:39:34.180Z,1513193974.180 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2017-12-13T19:39:34.180Z,1513193974.180 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:39:34.219Z,1513193974.219 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:39:34.600Z,1513193974.600 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:39:34.600Z,1513193974.600 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2017-12-13T19:39:34.967Z,1513193974.967 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:39:34.968Z,1513193974.968 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:39:34.968Z,1513193974.968 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:40:17.102Z,1513194017.102 [DataOverHttps](IMPORTANT): SBD MTMSN=20171213T194000
2017-12-13T19:40:22.470Z,1513194022.470 [DataOverHttps](INFO): Received command:get CANONSampler.loadAtStartup
2017-12-13T19:40:23.144Z,1513194023.144 [CommandLine](IMPORTANT): got command get CANONSampler.loadAtStartup
2017-12-13T19:40:23.144Z,1513194023.144 [CommandLine](IMPORTANT): CANONSampler.loadAtStartup 1 bool
2017-12-13T19:40:23.634Z,1513194023.634 [DataOverHttps](IMPORTANT): SBD MTMSN=20171213T194006
2017-12-13T19:40:24.069Z,1513194024.069 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:40:24.069Z,1513194024.069 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:40:24.069Z,1513194024.069 [BPC1](ERROR): Communications Fault
2017-12-13T19:40:24.090Z,1513194024.090 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:40:25.287Z,1513194025.287 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:40:25.287Z,1513194025.287 [BPC1] No Fault, FailCount= 1
2017-12-13T19:40:33.729Z,1513194033.729 [DataOverHttps](INFO): Received command:get CANONSampler.simulateHardware
2017-12-13T19:40:34.459Z,1513194034.459 [CommandLine](IMPORTANT): got command get CANONSampler.simulateHardware
2017-12-13T19:40:34.459Z,1513194034.459 [CommandLine](IMPORTANT): CANONSampler.simulateHardware 0 bool
2017-12-13T19:41:09.782Z,1513194069.782 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T19:41:09.782Z,1513194069.782 [Default:CheckIn:C.Wait] Stopped
2017-12-13T19:41:09.782Z,1513194069.782 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T19:41:09.782Z,1513194069.782 [Default:CheckIn:D] Running Loop=1
2017-12-13T19:41:10.181Z,1513194070.181 [Default:CheckIn:D] Stopped
2017-12-13T19:41:10.181Z,1513194070.181 [Default:CheckIn:E] Running Loop=1
2017-12-13T19:41:10.583Z,1513194070.583 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.063169 min 
2017-12-13T19:41:10.583Z,1513194070.583 [Default:CheckIn:E] Stopped
2017-12-13T19:41:10.584Z,1513194070.584 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T19:41:10.584Z,1513194070.584 [Default:CheckIn] Stopped
2017-12-13T19:41:10.584Z,1513194070.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T19:41:10.584Z,1513194070.584 [Default:CheckIn](INFO): Running loop #6
2017-12-13T19:41:10.584Z,1513194070.584 [Default:CheckIn] Running Loop=6
2017-12-13T19:41:10.584Z,1513194070.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T19:41:10.584Z,1513194070.584 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T19:41:11.338Z,1513194071.338 [NAL9602](INFO): Powering up
2017-12-13T19:41:21.377Z,1513194081.377 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:41:21.377Z,1513194081.377 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:41:21.377Z,1513194081.377 [BPC1](ERROR): Communications Fault
2017-12-13T19:41:21.399Z,1513194081.399 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:41:22.138Z,1513194082.138 [NAL9602](INFO): NAL9602 initialized
2017-12-13T19:41:22.625Z,1513194082.625 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:41:22.625Z,1513194082.625 [BPC1] No Fault, FailCount= 1
2017-12-13T19:41:43.347Z,1513194103.347 [NAL9602](INFO): SBD MO Status=2, MOMSN=3664, MT Status=2, MTMSN=0
2017-12-13T19:41:43.347Z,1513194103.347 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:41:43.391Z,1513194103.391 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:41:43.391Z,1513194103.391 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:41:43.391Z,1513194103.391 [BPC1](ERROR): Communications Fault
2017-12-13T19:41:43.442Z,1513194103.442 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:41:44.612Z,1513194104.612 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:41:44.612Z,1513194104.612 [BPC1] No Fault, FailCount= 2
2017-12-13T19:41:54.537Z,1513194114.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:42:12.547Z,1513194132.547 [NAL9602](INFO): SBD MO Status=2, MOMSN=3664, MT Status=2, MTMSN=0
2017-12-13T19:42:12.547Z,1513194132.547 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:42:24.173Z,1513194144.173 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:42:24.173Z,1513194144.173 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:42:24.173Z,1513194144.173 [BPC1](ERROR): Communications Fault
2017-12-13T19:42:24.194Z,1513194144.194 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:42:25.407Z,1513194145.407 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:42:25.408Z,1513194145.408 [BPC1] No Fault, FailCount= 1
2017-12-13T19:42:32.947Z,1513194152.947 [NAL9602](INFO): SBD MO Status=0, MOMSN=3664, MT Status=0, MTMSN=0
2017-12-13T19:42:32.947Z,1513194152.947 [NAL9602](INFO): No messages in MT queue
2017-12-13T19:42:34.150Z,1513194154.150 [NAL9602](INFO): GPS fix at 20171213T194216: (36.761656, -122.012615)
2017-12-13T19:42:34.218Z,1513194154.218 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T19:42:34.218Z,1513194154.218 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T19:42:35.271Z,1513194155.271 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:42:35.271Z,1513194155.271 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T19:42:35.271Z,1513194155.271 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:42:35.284Z,1513194155.284 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:42:35.465Z,1513194155.465 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:42:35.465Z,1513194155.465 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T19:42:35.792Z,1513194155.792 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:42:35.792Z,1513194155.792 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:42:35.805Z,1513194155.805 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:42:44.005Z,1513194164.005 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20171213T190615/Courier0028.lzma
2017-12-13T19:42:44.759Z,1513194164.759 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Courier0028.lzma.bak
2017-12-13T19:42:44.759Z,1513194164.759 [DataOverHttps](INFO): SBD MOMSN=5435976
2017-12-13T19:43:06.906Z,1513194186.906 [NAL9602](INFO): Powering down
2017-12-13T19:43:10.536Z,1513194190.536 [PNI_TCM](ERROR): CRC does not match. Expected:0x25467 got:0x254
2017-12-13T19:43:14.932Z,1513194194.932 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287
2017-12-13T19:43:16.538Z,1513194196.538 [PNI_TCM](ERROR): CRC does not match. Expected:0x11201 got:0x63135
2017-12-13T19:43:20.533Z,1513194200.533 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:43:20.533Z,1513194200.533 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:43:20.533Z,1513194200.533 [BPC1](ERROR): Communications Fault
2017-12-13T19:43:20.593Z,1513194200.593 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:43:21.781Z,1513194201.781 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:43:21.781Z,1513194201.781 [BPC1] No Fault, FailCount= 1
2017-12-13T19:43:45.698Z,1513194225.698 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:44:12.134Z,1513194252.134 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T19:44:12.134Z,1513194252.134 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:44:12.134Z,1513194252.134 [BPC1](ERROR): Data Fault
2017-12-13T19:44:12.155Z,1513194252.155 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:44:13.393Z,1513194253.393 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:44:13.393Z,1513194253.393 [BPC1] No Fault, FailCount= 1
2017-12-13T19:44:16.094Z,1513194256.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:44:34.137Z,1513194274.137 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:44:34.137Z,1513194274.137 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:44:34.137Z,1513194274.137 [BPC1](ERROR): Communications Fault
2017-12-13T19:44:34.193Z,1513194274.193 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:44:35.378Z,1513194275.378 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:44:35.378Z,1513194275.378 [BPC1] No Fault, FailCount= 2
2017-12-13T19:44:46.894Z,1513194286.894 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:44:55.978Z,1513194295.978 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T190615/Courier0032.lzma
2017-12-13T19:44:56.723Z,1513194296.723 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Courier0032.lzma.bak
2017-12-13T19:44:56.723Z,1513194296.723 [DataOverHttps](INFO): SBD MOMSN=5435980
2017-12-13T19:45:06.225Z,1513194306.225 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20171213T190615/Express0029.lzma
2017-12-13T19:45:07.003Z,1513194307.003 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Express0029.lzma.bak
2017-12-13T19:45:07.003Z,1513194307.003 [DataOverHttps](INFO): SBD MOMSN=5435982
2017-12-13T19:45:11.073Z,1513194311.073 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:45:11.073Z,1513194311.073 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:45:11.073Z,1513194311.073 [BPC1](ERROR): Communications Fault
2017-12-13T19:45:11.128Z,1513194311.128 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:45:12.037Z,1513194312.037 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:45:12.037Z,1513194312.037 [BPC1] No Fault, FailCount= 1
2017-12-13T19:45:16.083Z,1513194316.083 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171213T190615/Express0033.lzma
2017-12-13T19:45:16.815Z,1513194316.815 [DataOverHttps](INFO): Moved sent file to Logs/20171213T190615/Express0033.lzma.bak
2017-12-13T19:45:16.815Z,1513194316.815 [DataOverHttps](INFO): SBD MOMSN=5435991
2017-12-13T19:45:17.496Z,1513194317.496 [Default:CheckIn:Read_Iridium] Stopped
2017-12-13T19:45:17.497Z,1513194317.497 [Default:CheckIn:C.Wait] Running Loop=1
2017-12-13T19:45:17.497Z,1513194317.497 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-12-13T19:45:35.885Z,1513194335.885 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:45:35.885Z,1513194335.885 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2017-12-13T19:45:35.885Z,1513194335.885 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:45:35.898Z,1513194335.898 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:45:36.312Z,1513194336.312 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:45:36.312Z,1513194336.312 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2017-12-13T19:45:36.692Z,1513194336.692 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:45:36.692Z,1513194336.692 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:45:36.697Z,1513194336.697 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:46:09.484Z,1513194369.484 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:46:09.485Z,1513194369.485 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:46:09.485Z,1513194369.485 [BPC1](ERROR): Communications Fault
2017-12-13T19:46:09.506Z,1513194369.506 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:46:10.720Z,1513194370.720 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:46:10.721Z,1513194370.721 [BPC1] No Fault, FailCount= 1
2017-12-13T19:46:28.429Z,1513194388.429 [CBIT](DEBUG): EFC running - data check-sum false
2017-12-13T19:47:05.955Z,1513194425.955 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T19:47:05.955Z,1513194425.955 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:47:05.955Z,1513194425.955 [BPC1](ERROR): Data Fault
2017-12-13T19:47:05.998Z,1513194425.998 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:47:07.191Z,1513194427.191 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:47:07.191Z,1513194427.191 [BPC1] No Fault, FailCount= 1
2017-12-13T19:48:04.836Z,1513194484.836 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:48:04.836Z,1513194484.836 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:48:04.836Z,1513194484.836 [BPC1](ERROR): Communications Fault
2017-12-13T19:48:04.858Z,1513194484.858 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:48:06.059Z,1513194486.059 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:48:06.059Z,1513194486.059 [BPC1] No Fault, FailCount= 1
2017-12-13T19:48:29.027Z,1513194509.027 [PNI_TCM](ERROR): CRC does not match. Expected:0x34487 got:0x7449
2017-12-13T19:48:32.629Z,1513194512.629 [PNI_TCM](ERROR): CRC does not match. Expected:0x19532 got:0x7106
2017-12-13T19:48:34.902Z,1513194514.902 [Radio_Surface](INFO): Powering down
2017-12-13T19:48:35.438Z,1513194515.438 [PNI_TCM](ERROR): CRC does not match. Expected:0x34824 got:0x17162
2017-12-13T19:48:37.081Z,1513194517.081 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:48:37.081Z,1513194517.081 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2017-12-13T19:48:37.081Z,1513194517.081 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:48:37.106Z,1513194517.106 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:48:37.488Z,1513194517.488 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:48:37.488Z,1513194517.488 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2017-12-13T19:48:37.859Z,1513194517.859 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:48:37.859Z,1513194517.859 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:48:37.860Z,1513194517.860 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:48:40.961Z,1513194520.961 [Radio_Surface](INFO): Powering up
2017-12-13T19:49:04.226Z,1513194544.226 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:49:04.226Z,1513194544.226 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:49:04.226Z,1513194544.226 [BPC1](ERROR): Communications Fault
2017-12-13T19:49:04.269Z,1513194544.269 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:49:05.487Z,1513194545.487 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:49:05.487Z,1513194545.487 [BPC1] No Fault, FailCount= 1
2017-12-13T19:49:48.197Z,1513194588.197 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:49:57.039Z,1513194597.039 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:49:57.039Z,1513194597.039 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:49:57.039Z,1513194597.039 [BPC1](ERROR): Communications Fault
2017-12-13T19:49:57.102Z,1513194597.102 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:49:58.279Z,1513194598.279 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:49:58.279Z,1513194598.279 [BPC1] No Fault, FailCount= 1
2017-12-13T19:50:17.853Z,1513194617.853 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-12-13T19:50:17.853Z,1513194617.853 [Default:CheckIn:C.Wait] Stopped
2017-12-13T19:50:17.853Z,1513194617.853 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-12-13T19:50:17.853Z,1513194617.853 [Default:CheckIn:D] Running Loop=1
2017-12-13T19:50:18.255Z,1513194618.255 [Default:CheckIn:D] Stopped
2017-12-13T19:50:18.255Z,1513194618.255 [Default:CheckIn:E] Running Loop=1
2017-12-13T19:50:18.665Z,1513194618.665 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.197742 min 
2017-12-13T19:50:18.665Z,1513194618.665 [Default:CheckIn:E] Stopped
2017-12-13T19:50:18.666Z,1513194618.666 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-12-13T19:50:18.666Z,1513194618.666 [Default:CheckIn] Stopped
2017-12-13T19:50:18.666Z,1513194618.666 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-12-13T19:50:18.666Z,1513194618.666 [Default:CheckIn](INFO): Running loop #7
2017-12-13T19:50:18.666Z,1513194618.666 [Default:CheckIn] Running Loop=7
2017-12-13T19:50:18.666Z,1513194618.666 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-12-13T19:50:18.666Z,1513194618.666 [Default:CheckIn:Read_GPS] Running Loop=1
2017-12-13T19:50:19.038Z,1513194619.038 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:50:19.039Z,1513194619.039 [BPC1] Communications Fault, FailCount= 2
2017-12-13T19:50:19.039Z,1513194619.039 [BPC1](ERROR): Communications Fault
2017-12-13T19:50:19.101Z,1513194619.101 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:50:19.402Z,1513194619.402 [NAL9602](INFO): Powering up
2017-12-13T19:50:20.293Z,1513194620.293 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:50:20.293Z,1513194620.293 [BPC1] No Fault, FailCount= 2
2017-12-13T19:50:30.203Z,1513194630.203 [NAL9602](INFO): NAL9602 initialized
2017-12-13T19:50:48.202Z,1513194648.202 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:50:56.237Z,1513194656.237 [BPC1](ERROR): Failed to parse bank B battery data
2017-12-13T19:50:56.237Z,1513194656.237 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:50:56.237Z,1513194656.237 [BPC1](ERROR): Data Fault
2017-12-13T19:50:56.279Z,1513194656.279 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:50:57.508Z,1513194657.508 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:50:57.508Z,1513194657.508 [BPC1] No Fault, FailCount= 1
2017-12-13T19:51:04.607Z,1513194664.607 [NAL9602](INFO): SBD MO Status=2, MOMSN=3665, MT Status=2, MTMSN=0
2017-12-13T19:51:04.608Z,1513194664.608 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:51:23.007Z,1513194683.007 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.004196
2017-12-13T19:51:37.868Z,1513194697.868 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:51:37.868Z,1513194697.868 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2017-12-13T19:51:37.868Z,1513194697.868 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:51:37.881Z,1513194697.881 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:51:38.263Z,1513194698.263 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:51:38.263Z,1513194698.263 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2017-12-13T19:51:38.661Z,1513194698.661 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:51:38.661Z,1513194698.661 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:51:38.662Z,1513194698.662 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:51:55.027Z,1513194715.027 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:51:55.027Z,1513194715.027 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:51:55.027Z,1513194715.027 [BPC1](ERROR): Communications Fault
2017-12-13T19:51:55.073Z,1513194715.073 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:51:56.281Z,1513194716.281 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:51:56.281Z,1513194716.281 [BPC1] No Fault, FailCount= 1
2017-12-13T19:51:58.607Z,1513194718.607 [NAL9602](INFO): SBD MO Status=2, MOMSN=3665, MT Status=2, MTMSN=0
2017-12-13T19:51:58.608Z,1513194718.608 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:52:15.807Z,1513194735.807 [NAL9602](INFO): SBD MO Status=2, MOMSN=3665, MT Status=2, MTMSN=0
2017-12-13T19:52:15.807Z,1513194735.807 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:52:45.835Z,1513194765.835 [PNI_TCM](ERROR): CRC does not match. Expected:0x4523 got:0x129
2017-12-13T19:52:48.207Z,1513194768.207 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:52:49.027Z,1513194769.027 [PNI_TCM](ERROR): CRC does not match. Expected:0x24981 got:0x6462
2017-12-13T19:52:54.242Z,1513194774.242 [BPC1](ERROR): Failed to receive battery data
2017-12-13T19:52:54.242Z,1513194774.242 [BPC1] Communications Fault, FailCount= 1
2017-12-13T19:52:54.242Z,1513194774.242 [BPC1](ERROR): Communications Fault
2017-12-13T19:52:54.282Z,1513194774.282 [CBIT](ERROR): Communications Fault in component: BPC1
2017-12-13T19:52:55.470Z,1513194775.470 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:52:55.470Z,1513194775.470 [BPC1] No Fault, FailCount= 1
2017-12-13T19:52:57.808Z,1513194777.808 [NAL9602](INFO): SBD MO Status=2, MOMSN=3665, MT Status=2, MTMSN=0
2017-12-13T19:52:57.809Z,1513194777.809 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:53:08.215Z,1513194788.215 [NAL9602](INFO): SBD MO Status=0, MOMSN=3665, MT Status=0, MTMSN=0
2017-12-13T19:53:08.215Z,1513194788.215 [NAL9602](INFO): No messages in MT queue
2017-12-13T19:53:09.406Z,1513194789.406 [NAL9602](INFO): GPS fix at 20171213T195250: (36.760233, -122.013086)
2017-12-13T19:53:09.477Z,1513194789.477 [Default:CheckIn:Read_GPS] Stopped
2017-12-13T19:53:09.478Z,1513194789.478 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-12-13T19:53:20.315Z,1513194800.315 [NAL9602](INFO): SBD MO Status=1, MOMSN=3666, MT Status=0, MTMSN=0
2017-12-13T19:53:20.362Z,1513194800.362 [NAL9602](INFO): Sent 63 bytes from file Logs/20171213T190615/Courier0036.lzma
2017-12-13T19:53:20.362Z,1513194800.362 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:53:20.363Z,1513194800.363 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T190615/Courier0036.lzma.parts/0000.sbd
2017-12-13T19:53:20.364Z,1513194800.364 [NAL9602](DEBUG): Completed sending Logs/20171213T190615/Courier0036.lzma
2017-12-13T19:53:47.684Z,1513194827.684 [BPC1](ERROR): Failed to parse bank A battery data
2017-12-13T19:53:47.686Z,1513194827.686 [BPC1] Data Fault, FailCount= 1
2017-12-13T19:53:47.686Z,1513194827.686 [BPC1](ERROR): Data Fault
2017-12-13T19:53:47.708Z,1513194827.708 [CBIT](ERROR): Data Fault in component: BPC1
2017-12-13T19:53:48.445Z,1513194828.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-12-13T19:53:48.905Z,1513194828.905 [CBIT](INFO): Clearing failed state for component BPC1
2017-12-13T19:53:48.905Z,1513194828.905 [BPC1] No Fault, FailCount= 1
2017-12-13T19:54:19.653Z,1513194859.653 [NAL9602](INFO): SBD MO Status=2, MOMSN=3667, MT Status=2, MTMSN=0
2017-12-13T19:54:19.653Z,1513194859.653 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-12-13T19:54:38.862Z,1513194878.862 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-12-13T19:54:38.862Z,1513194878.862 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2017-12-13T19:54:38.862Z,1513194878.862 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2017-12-13T19:54:38.874Z,1513194878.874 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2017-12-13T19:54:39.256Z,1513194879.256 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2017-12-13T19:54:39.256Z,1513194879.256 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2017-12-13T19:54:39.626Z,1513194879.626 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-12-13T19:54:39.626Z,1513194879.626 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-12-13T19:54:39.626Z,1513194879.626 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-12-13T19:54:40.375Z,1513194880.375 [DataOverHttps](IMPORTANT): SBD MTMSN=20171213T195421
2017-12-13T19:54:40.378Z,1513194880.378 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002900
2017-12-13T19:54:48.091Z,1513194888.091 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=3667, MT Status=1, MTMSN=1225
2017-12-13T19:54:48.146Z,1513194888.146 [NAL9602](INFO): Sent 147 bytes from file Logs/20171213T190615/Express0037.lzma
2017-12-13T19:54:48.146Z,1513194888.146 [NAL9602](INFO): Packets left to send: 0
2017-12-13T19:54:48.148Z,1513194888.148 [NAL9602](DEBUG): Stored copy of sent data in Logs/20171213T190615/Express0037.lzma.parts/0000.sbd
2017-12-13T19:54:48.148Z,1513194888.148 [NAL9602](DEBUG): Completed sending Logs/20171213T190615/Express0037.lzma
2017-12-13T19:54:48.781Z,1513194888.781 [NAL9602](INFO): Received command:restart logs