2017-09-06T16:26:56.345Z,1504715216.345 [CommandLine](IMPORTANT): got command restart logs 2017-09-06T16:27:57.182Z,1504715277.182 [DataOverHttps](IMPORTANT): SBD MTMSN=20170906T162641 2017-09-06T16:28:01.547Z,1504715281.547 [DataOverHttps](INFO): Received command:sched asap "load Maintenance/rotate_sampler.xml;set rotate_sampler.Depth01 0 meter;set rotate_sampler.Speed 0 meter_per_second;set rotate_sampler.MinAltitude 0 meter;set rotate_sampler:NeedComms.SurfaceSpeed 0 meter_per_second" t1n8 1 2 2017-09-06T16:28:01.596Z,1504715281.596 [CommandLine](IMPORTANT): got command schedule asap "load Maintenance/rotate_sampler.xml;set rotate_sampler.Depth01 0 meter;set rotate_sampler.Speed 0 meter_per_second;set rotate_sampler.MinAltitude 0 meter;set rotate_sampler:NeedComms.SurfaceSpeed 0 meter_per_second" t1n8 1 2.000000 2017-09-06T16:28:01.596Z,1504715281.596 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=t1n8 2017-09-06T16:28:01.597Z,1504715281.597 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='t1n8'): "load Maintenance/rotate_sampler.xml;set rotate_sampler.Depth01 0 meter;set rotate_sampler.Speed 0 meter_per_second;set rotate_sampler.MinAltitude 0 meter;set rotate_sampler:NeedComms.SurfaceSpeed 0 meter_per_second" ASAP 2017-09-06T16:28:02.298Z,1504715282.298 [DataOverHttps](IMPORTANT): SBD MTMSN=20170906T162647 2017-09-06T16:28:06.567Z,1504715286.567 [DataOverHttps](INFO): Received command:sched asap "set rotate_sampler:NeedComms.GPSTimeout 1 minute;set rotate_sampler:SampleAtDepth.TargetDepth 0 meter;run " t1n8 2 2 2017-09-06T16:28:06.624Z,1504715286.624 [CommandLine](IMPORTANT): got command schedule asap "set rotate_sampler:NeedComms.GPSTimeout 1 minute;set rotate_sampler:SampleAtDepth.TargetDepth 0 meter;run " t1n8 2 2.000000 2017-09-06T16:28:06.624Z,1504715286.624 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=t1n8 2017-09-06T16:28:06.625Z,1504715286.625 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='t1n8'): "set rotate_sampler:NeedComms.GPSTimeout 1 minute;set rotate_sampler:SampleAtDepth.TargetDepth 0 meter;run " ASAP 2017-09-06T16:28:06.651Z,1504715286.651 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/rotate_sampler.xml 2017-09-06T16:28:06.652Z,1504715286.652 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/rotate_sampler.xml 2017-09-06T16:28:06.797Z,1504715286.797 [MissionManager](INFO): DefineArg rotate_sampler.MissionTimeout = 1.000000 h 2017-09-06T16:28:06.801Z,1504715286.801 [MissionManager](INFO): DefineArg rotate_sampler.BuoyancyNeutral = 315.000012 cc 2017-09-06T16:28:06.813Z,1504715286.813 [MissionManager](INFO): DefineArg rotate_sampler.MassDefault = -1.300000 cm 2017-09-06T16:28:06.816Z,1504715286.816 [MissionManager](INFO): DefineArg rotate_sampler.Rotate = 1.000000 bool 2017-09-06T16:28:06.819Z,1504715286.819 [MissionManager](INFO): DefineArg rotate_sampler.Depth01 = 40.000000 m 2017-09-06T16:28:06.849Z,1504715286.849 [MissionManager](INFO): DefineArg rotate_sampler.SettleTime = 0.000000 s 2017-09-06T16:28:06.852Z,1504715286.852 [MissionManager](INFO): DefineArg rotate_sampler.RudderAngle = 13.000000 arcdeg 2017-09-06T16:28:06.855Z,1504715286.855 [MissionManager](INFO): DefineArg rotate_sampler.Speed = 1.000000 m/s 2017-09-06T16:28:06.858Z,1504715286.858 [MissionManager](INFO): DefineArg rotate_sampler.MinAltitude = 5.000000 m 2017-09-06T16:28:06.877Z,1504715286.877 [MissionManager](INFO): DefineArg rotate_sampler.MaxDepth = 225.000000 m 2017-09-06T16:28:06.877Z,1504715286.877 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml 2017-09-06T16:28:07.192Z,1504715287.192 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUS = 0 bool 2017-09-06T16:28:07.195Z,1504715287.195 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUSmaxPitch = 60.000000 arcdeg 2017-09-06T16:28:07.202Z,1504715287.202 [MissionManager](INFO): DefineArg rotate_sampler:Science.SampleISUSminPitch = -60.000000 arcdeg 2017-09-06T16:28:07.209Z,1504715287.209 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakDetectChlActive = 0 bool 2017-09-06T16:28:07.216Z,1504715287.216 [MissionManager](INFO): DefineArg rotate_sampler:Science.HighestChlPeakReportActive = 0 bool 2017-09-06T16:28:07.221Z,1504715287.221 [MissionManager](INFO): DefineArg rotate_sampler:Science.PatchTracking = 0 bool 2017-09-06T16:28:07.224Z,1504715287.224 [MissionManager](INFO): DefineArg rotate_sampler:Science.FilterWidthHorizontal = 3.000000 count 2017-09-06T16:28:07.227Z,1504715287.227 [MissionManager](INFO): DefineArg rotate_sampler:Science.NumProfilesSlidingwindow = 100.000000 count 2017-09-06T16:28:07.234Z,1504715287.234 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakDetectNO3Active = 0 bool 2017-09-06T16:28:07.241Z,1504715287.241 [MissionManager](INFO): DefineArg rotate_sampler:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2017-09-06T16:28:07.245Z,1504715287.245 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledAanderaaO2 = 1.000000 bool 2017-09-06T16:28:07.256Z,1504715287.256 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledNeilBrown = 1.000000 bool 2017-09-06T16:28:07.264Z,1504715287.264 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledSeabird = 1.000000 bool 2017-09-06T16:28:07.267Z,1504715287.267 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledTurbulence_NPS = 0.000000 bool 2017-09-06T16:28:07.283Z,1504715287.283 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledWetLabsBB2FL = 1.000000 bool 2017-09-06T16:28:07.286Z,1504715287.286 [MissionManager](INFO): DefineArg rotate_sampler:Science.EnabledISUS = 0.000000 bool 2017-09-06T16:28:07.290Z,1504715287.290 [MissionManager](INFO): DefineArg rotate_sampler:Science.LowPassWindowLength = 20.000000 count 2017-09-06T16:28:07.293Z,1504715287.293 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakChlShallowBound = nan m 2017-09-06T16:28:07.312Z,1504715287.312 [MissionManager](INFO): DefineArg rotate_sampler:Science.PeakChlDeepBound = nan m 2017-09-06T16:28:07.324Z,1504715287.324 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChl = 0.000000 ug/l 2017-09-06T16:28:07.327Z,1504715287.327 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlDepth = 0.000000 m 2017-09-06T16:28:07.334Z,1504715287.334 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlLatitude = nan arcdeg 2017-09-06T16:28:07.341Z,1504715287.341 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakChlLongitude = nan arcdeg 2017-09-06T16:28:07.382Z,1504715287.382 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3 = 0.000000 umol/l 2017-09-06T16:28:07.386Z,1504715287.386 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Depth = 0.000000 m 2017-09-06T16:28:07.421Z,1504715287.421 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Latitude = nan arcdeg 2017-09-06T16:28:07.425Z,1504715287.425 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PeakNO3Longitude = nan arcdeg 2017-09-06T16:28:07.438Z,1504715287.438 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChl = 0.000000 m 2017-09-06T16:28:07.441Z,1504715287.441 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlDepth = 0.000000 m 2017-09-06T16:28:07.455Z,1504715287.455 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlLatitude = nan arcdeg 2017-09-06T16:28:07.458Z,1504715287.458 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlLongitude = nan arcdeg 2017-09-06T16:28:07.485Z,1504715287.485 [MissionManager](INFO): DefineOutput rotate_sampler:Science.PatchChlDistance = nan arcdeg 2017-09-06T16:28:07.530Z,1504715287.530 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2017-09-06T16:28:07.577Z,1504715287.577 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2017-09-06T16:28:07.612Z,1504715287.612 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2017-09-06T16:28:07.646Z,1504715287.646 [MissionManager](INFO): RedefineArg rotate_sampler:Science.SampleISUS = value:1 bool 2017-09-06T16:28:07.647Z,1504715287.647 [MissionManager](INFO): RedefineArg rotate_sampler:Science.PeakDetectChlActive = value:1 bool 2017-09-06T16:28:07.660Z,1504715287.660 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml 2017-09-06T16:28:07.802Z,1504715287.802 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.DiveInterval = 3.000000 h 2017-09-06T16:28:07.817Z,1504715287.817 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.AcousticTimeout = 96.000000 h 2017-09-06T16:28:07.820Z,1504715287.820 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.WaitForPitchUp = 10.000000 min 2017-09-06T16:28:07.832Z,1504715287.832 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.SurfacePitch = 20.000000 arcdeg 2017-09-06T16:28:07.843Z,1504715287.843 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.SurfaceSpeed = 1.000000 m/s 2017-09-06T16:28:07.850Z,1504715287.850 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.GPSTimeout = 7.000000 min 2017-09-06T16:28:07.853Z,1504715287.853 [CBIT](DEBUG): EFC running - data check-sum false 2017-09-06T16:28:07.873Z,1504715287.873 [MissionManager](INFO): DefineArg rotate_sampler:NeedComms.CommsTimeout = 30.000000 min 2017-09-06T16:28:07.894Z,1504715287.894 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-06T16:28:07.938Z,1504715287.938 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml 2017-09-06T16:28:07.980Z,1504715287.980 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MinAltitude = 5.000000 m 2017-09-06T16:28:07.983Z,1504715287.983 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MaxDepth = 200.000000 m 2017-09-06T16:28:07.986Z,1504715287.986 [MissionManager](INFO): DefineArg rotate_sampler:StandardEnvelopes.MinOffshore = 2000.000000 m 2017-09-06T16:28:07.988Z,1504715287.988 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2017-09-06T16:28:08.003Z,1504715288.003 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2017-09-06T16:28:08.025Z,1504715288.025 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2017-09-06T16:28:08.059Z,1504715288.059 [rotate_sampler:I.Pitch](DEBUG): Construct. 2017-09-06T16:28:08.065Z,1504715288.065 [rotate_sampler:J.Buoyancy](DEBUG): Construct Buoyancy. 2017-09-06T16:28:08.086Z,1504715288.086 [rotate_sampler:K.SetSpeed](DEBUG): Construct. 2017-09-06T16:28:08.091Z,1504715288.091 [rotate_sampler:M.Point](DEBUG): Construct. 2017-09-06T16:28:08.113Z,1504715288.113 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml 2017-09-06T16:28:08.287Z,1504715288.287 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.TargetDepth = 5.000000 m 2017-09-06T16:28:08.290Z,1504715288.290 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.SettleTime = 30.000000 s 2017-09-06T16:28:08.293Z,1504715288.293 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.RotateOnly = 0.000000 bool 2017-09-06T16:28:08.297Z,1504715288.297 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.UseCANONSampler = 1.000000 bool 2017-09-06T16:28:08.320Z,1504715288.320 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.UseESP = 0.000000 bool 2017-09-06T16:28:08.323Z,1504715288.323 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min 2017-09-06T16:28:08.327Z,1504715288.327 [MissionManager](INFO): DefineArg rotate_sampler:SampleAtDepth.CANONSamplerTimeout = 6.000000 min 2017-09-06T16:28:08.329Z,1504715288.329 [rotate_sampler:SampleAtDepth:B.Pitch](DEBUG): Construct. 2017-09-06T16:28:08.348Z,1504715288.348 [rotate_sampler:SampleAtDepth:D.Wait](DEBUG): Construct Wait. 2017-09-06T16:28:08.375Z,1504715288.375 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute. 2017-09-06T16:28:08.393Z,1504715288.393 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute. 2017-09-06T16:28:08.424Z,1504715288.424 [MissionManager](INFO): DefineArg rotate_sampler:SampleWithinEnvelopesWrapper.TargetDepth = 1.000000 m 2017-09-06T16:28:08.509Z,1504715288.509 [MissionManager](DEBUG): Rotate the sampler 1x at a specified depth Maximum duration of mission 1 Buoyancy bladder position while performing the YoYo behavior. Defaults to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc for active buoyancy Static setting for mass during the mission. Set to NaN mm for active mass position 1 Depth of sample 1. 40 How long to wait after reaching target depth before triggering sample. 0 Rudder angle to use while performing the spiral cast. 13 Speed while performing the spiral cast. 1 Minimum altitude for the entire mission. 5 Maximum depth for the entire mission. 225 1 2017-09-06T16:28:08.510Z,1504715288.510 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/rotate_sampler.xml 2017-09-06T16:28:15.410Z,1504715295.410 [CommandLine](IMPORTANT): got command set rotate_sampler.Depth01 0.000000 meter 2017-09-06T16:28:15.411Z,1504715295.411 [CommandLine](IMPORTANT): got command set rotate_sampler.Speed 0.000000 meter_per_second 2017-09-06T16:28:15.412Z,1504715295.412 [CommandLine](IMPORTANT): got command set rotate_sampler.MinAltitude 0.000000 meter 2017-09-06T16:28:15.413Z,1504715295.413 [CommandLine](IMPORTANT): got command set rotate_sampler:NeedComms.SurfaceSpeed 0.000000 meter_per_second 2017-09-06T16:28:20.046Z,1504715300.046 [CommandLine](IMPORTANT): got command set rotate_sampler:NeedComms.GPSTimeout 1.000000 minute 2017-09-06T16:28:20.047Z,1504715300.047 [CommandLine](IMPORTANT): got command set rotate_sampler:SampleAtDepth.TargetDepth 0.000000 meter 2017-09-06T16:28:20.048Z,1504715300.048 [CommandLine](IMPORTANT): got command run 2017-09-06T16:28:20.051Z,1504715300.051 [CommandLine](IMPORTANT): Running 2017-09-06T16:28:20.207Z,1504715300.207 [Default] Stopped 2017-09-06T16:28:20.208Z,1504715300.208 [Default](DEBUG): Aggregate::uninitialize Default 2017-09-06T16:28:20.208Z,1504715300.208 [Default:B.GoToSurface] Stopped 2017-09-06T16:28:20.208Z,1504715300.208 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-09-06T16:28:20.208Z,1504715300.208 [Default:CheckIn] Stopped 2017-09-06T16:28:20.208Z,1504715300.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-09-06T16:28:20.208Z,1504715300.208 [Default:CheckIn:C.Wait] Stopped 2017-09-06T16:28:20.208Z,1504715300.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2017-09-06T16:28:20.208Z,1504715300.208 [MissionManager](IMPORTANT): Started mission rotate_sampler 2017-09-06T16:28:20.208Z,1504715300.208 [rotate_sampler] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler](DEBUG): Aggregate::initialize rotate_sampler 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science](DEBUG): Aggregate::initialize rotate_sampler:Science 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:A] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:B] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:C] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:D] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:E] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:F] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:PeakDetectChl] Running Loop=1 2017-09-06T16:28:20.209Z,1504715300.209 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectChl 2017-09-06T16:28:20.210Z,1504715300.210 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2017-09-06T16:28:20.210Z,1504715300.210 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2017-09-06T16:28:20.212Z,1504715300.212 [rotate_sampler:Science:HighestChlPeakReport] Running Loop=1 2017-09-06T16:28:20.212Z,1504715300.212 [rotate_sampler:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize rotate_sampler:Science:HighestChlPeakReport 2017-09-06T16:28:20.212Z,1504715300.212 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2017-09-06T16:28:20.212Z,1504715300.212 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2017-09-06T16:28:20.213Z,1504715300.213 [rotate_sampler:Science:PeakDetectNO3] Running Loop=1 2017-09-06T16:28:20.213Z,1504715300.213 [rotate_sampler:Science:PeakDetectNO3](DEBUG): Aggregate::initialize rotate_sampler:Science:PeakDetectNO3 2017-09-06T16:28:20.213Z,1504715300.213 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2017-09-06T16:28:20.213Z,1504715300.213 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2017-09-06T16:28:20.215Z,1504715300.215 [rotate_sampler:StandardEnvelopes] Running Loop=1 2017-09-06T16:28:20.215Z,1504715300.215 [rotate_sampler:StandardEnvelopes](DEBUG): Aggregate::initialize rotate_sampler:StandardEnvelopes 2017-09-06T16:28:20.215Z,1504715300.215 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2017-09-06T16:28:20.215Z,1504715300.215 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2017-09-06T16:28:20.215Z,1504715300.215 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2017-09-06T16:28:20.216Z,1504715300.216 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2017-09-06T16:28:20.216Z,1504715300.216 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2017-09-06T16:28:20.216Z,1504715300.216 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2017-09-06T16:28:20.216Z,1504715300.216 [rotate_sampler:I.Pitch] Running Loop=1 2017-09-06T16:28:20.216Z,1504715300.216 [rotate_sampler:I.Pitch](DEBUG): Initialize. 2017-09-06T16:28:20.216Z,1504715300.216 [rotate_sampler:J.Buoyancy] Running Loop=1 2017-09-06T16:28:20.217Z,1504715300.217 [rotate_sampler:J.Buoyancy](DEBUG): Initialize Buoyancy Component. 2017-09-06T16:28:20.217Z,1504715300.217 [rotate_sampler:K.SetSpeed] Running Loop=1 2017-09-06T16:28:20.217Z,1504715300.217 [rotate_sampler:K.SetSpeed](DEBUG): Initialize. 2017-09-06T16:28:20.217Z,1504715300.217 [rotate_sampler:M.Point] Running Loop=1 2017-09-06T16:28:20.217Z,1504715300.217 [rotate_sampler:M.Point](DEBUG): Initialize. 2017-09-06T16:28:20.217Z,1504715300.217 [rotate_sampler:C] Running Loop=1 2017-09-06T16:28:20.218Z,1504715300.218 [rotate_sampler:M.Point] Running Loop=1 2017-09-06T16:28:20.223Z,1504715300.223 [rotate_sampler:K.SetSpeed] Running Loop=1 2017-09-06T16:28:20.227Z,1504715300.227 [rotate_sampler:J.Buoyancy] Running Loop=1 2017-09-06T16:28:20.251Z,1504715300.251 [rotate_sampler:I.Pitch] Running Loop=1 2017-09-06T16:28:20.256Z,1504715300.256 [rotate_sampler:StandardEnvelopes] Running Loop=1 2017-09-06T16:28:20.256Z,1504715300.256 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2017-09-06T16:28:20.282Z,1504715300.282 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2017-09-06T16:28:20.300Z,1504715300.300 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2017-09-06T16:28:20.307Z,1504715300.307 [rotate_sampler:C] Stopped 2017-09-06T16:28:20.307Z,1504715300.307 [rotate_sampler:D] Running Loop=1 2017-09-06T16:28:20.307Z,1504715300.307 [rotate_sampler:Science] Running Loop=1 2017-09-06T16:28:20.322Z,1504715300.322 [rotate_sampler:Science:PeakDetectNO3] Stopped 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize rotate_sampler:Science:PeakDetectNO3 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:HighestChlPeakReport] Stopped 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize rotate_sampler:Science:HighestChlPeakReport 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2017-09-06T16:28:20.323Z,1504715300.323 [rotate_sampler:Science:PeakDetectChl] Running Loop=1 2017-09-06T16:28:20.327Z,1504715300.327 [rotate_sampler:Science:F] Running Loop=1 2017-09-06T16:28:20.328Z,1504715300.328 [rotate_sampler:Science:F](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2017-09-06T16:28:20.330Z,1504715300.330 [rotate_sampler:Science:E] Stopped 2017-09-06T16:28:20.330Z,1504715300.330 [rotate_sampler:Science:D] Stopped 2017-09-06T16:28:20.330Z,1504715300.330 [rotate_sampler:Science:C] Running Loop=1 2017-09-06T16:28:20.330Z,1504715300.330 [rotate_sampler:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2017-09-06T16:28:20.331Z,1504715300.331 [rotate_sampler:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2017-09-06T16:28:20.349Z,1504715300.349 [rotate_sampler:Science:B] Stopped 2017-09-06T16:28:20.349Z,1504715300.349 [rotate_sampler:Science:A] Running Loop=1 2017-09-06T16:28:20.350Z,1504715300.350 [rotate_sampler:Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water 2017-09-06T16:28:20.519Z,1504715300.519 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4 2017-09-06T16:28:20.519Z,1504715300.519 [WetLabsBB2FL](INFO): Powering up 2017-09-06T16:28:20.637Z,1504715300.637 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown. 2017-09-06T16:28:20.645Z,1504715300.645 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4 2017-09-06T16:28:20.654Z,1504715300.654 [rotate_sampler:D] Stopped 2017-09-06T16:28:20.654Z,1504715300.654 [rotate_sampler:F] Running Loop=1 2017-09-06T16:28:20.781Z,1504715300.781 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:21.012Z,1504715301.012 [rotate_sampler:F] Stopped 2017-09-06T16:28:21.012Z,1504715301.012 [rotate_sampler:G] Running Loop=1 2017-09-06T16:28:21.189Z,1504715301.189 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:21.358Z,1504715301.358 [ThrusterServo](FAULT): getVelocity uart error serial timeout 2017-09-06T16:28:21.358Z,1504715301.358 [ThrusterServo](FAULT): Thruster uart error: serial timeout 2017-09-06T16:28:21.358Z,1504715301.358 [ThrusterServo] Communications Fault, FailCount= 1 2017-09-06T16:28:21.358Z,1504715301.358 [ThrusterServo](ERROR): Communications Fault 2017-09-06T16:28:21.362Z,1504715301.362 [CBIT](ERROR): Communications Fault in component: ThrusterServo 2017-09-06T16:28:21.482Z,1504715301.482 [rotate_sampler:G] Stopped 2017-09-06T16:28:21.482Z,1504715301.482 [rotate_sampler:StartingMission] Running Loop=1 2017-09-06T16:28:21.502Z,1504715301.502 [rotate_sampler:StartingMission](DEBUG): Aggregate::initialize rotate_sampler:StartingMission 2017-09-06T16:28:21.602Z,1504715301.602 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:21.636Z,1504715301.636 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2017-09-06T16:28:21.636Z,1504715301.636 [ThrusterServo](INFO): Powering down 2017-09-06T16:28:21.850Z,1504715301.850 [rotate_sampler:NeedComms] Running Loop=1 2017-09-06T16:28:21.850Z,1504715301.850 [rotate_sampler:NeedComms](DEBUG): Aggregate::initialize rotate_sampler:NeedComms 2017-09-06T16:28:21.871Z,1504715301.871 [rotate_sampler:NeedComms:B.GoToSurface] Running Loop=1 2017-09-06T16:28:21.871Z,1504715301.871 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-09-06T16:28:21.871Z,1504715301.871 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-09-06T16:28:21.872Z,1504715301.872 [rotate_sampler:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2017-09-06T16:28:21.872Z,1504715301.872 [rotate_sampler:NeedComms:B.GoToSurface](INFO): Received speed setting 0.000000 m/s. 2017-09-06T16:28:21.872Z,1504715301.872 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-09-06T16:28:21.873Z,1504715301.873 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-09-06T16:28:21.873Z,1504715301.873 [rotate_sampler:NeedComms:A] Running Loop=1 2017-09-06T16:28:21.882Z,1504715301.882 [rotate_sampler:NeedComms:A](INFO): last time_fix was: 1504715120.000000 second since 1970/01/01T00:00:00Z 2017-09-06T16:28:21.888Z,1504715301.888 [rotate_sampler:NeedComms:A] Stopped 2017-09-06T16:28:22.015Z,1504715302.015 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:22.429Z,1504715302.429 [rotate_sampler:NeedComms:C] Running Loop=1 2017-09-06T16:28:22.672Z,1504715302.672 [CBIT](INFO): Clearing failed state for component ThrusterServo 2017-09-06T16:28:22.672Z,1504715302.672 [ThrusterServo] No Fault, FailCount= 1 2017-09-06T16:28:22.837Z,1504715302.837 [rotate_sampler:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2017-09-06T16:28:22.890Z,1504715302.890 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-06T16:28:22.897Z,1504715302.897 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-06T16:28:23.094Z,1504715303.094 [NAL9602](INFO): Powering up 2017-09-06T16:28:28.665Z,1504715308.665 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:29.479Z,1504715309.479 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 125.188 44.413 20.359 38.101 38.101 46.704 8.603 273.1 509.3 85.7 2017-09-06T16:28:29.479Z,1504715309.479 [Aanderaa_O2](DEBUG): Error parsing device response 2017-09-06T16:28:30.020Z,1504715310.020 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:30.280Z,1504715310.280 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 125.515 44.529 20.359 38.069 38.069 46.682 8.614 273.1 508.8 85.7 2017-09-06T16:28:30.280Z,1504715310.280 [Aanderaa_O2](DEBUG): Error parsing device response 2017-09-06T16:28:32.279Z,1504715312.279 [Aanderaa_O2](DEBUG): Error parsing data: 4831F 241 125.525 44.532 20.359 38.067 38.067 46.680 8.612 273.1 507.8 85.7 2017-09-06T16:28:32.279Z,1504715312.279 [Aanderaa_O2](DEBUG): Error parsing device response 2017-09-06T16:28:33.900Z,1504715313.900 [NAL9602](INFO): NAL9602 initialized 2017-09-06T16:28:35.256Z,1504715315.256 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:37.028Z,1504715317.028 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:38.084Z,1504715318.084 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:43.845Z,1504715323.845 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:46.466Z,1504715326.466 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:52.227Z,1504715332.227 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:54.847Z,1504715334.847 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:28:56.197Z,1504715336.197 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:04.258Z,1504715344.258 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:04.458Z,1504715344.458 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:08.452Z,1504715348.452 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:10.031Z,1504715350.031 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:12.639Z,1504715352.639 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:18.112Z,1504715358.112 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:22.543Z,1504715362.543 [rotate_sampler:NeedComms:C](INFO): Timed out from 2017-09-06T16:28:22.4Z 2017-09-06T16:29:22.543Z,1504715362.543 [rotate_sampler:NeedComms:C:A_Timeout] Running Loop=1 2017-09-06T16:29:22.543Z,1504715362.543 [rotate_sampler:NeedComms:C:A_Timeout](DEBUG): Aggregate::initialize rotate_sampler:NeedComms:C:A_Timeout 2017-09-06T16:29:22.544Z,1504715362.544 [rotate_sampler:NeedComms:C:A_Timeout:A] Running Loop=1 2017-09-06T16:29:22.544Z,1504715362.544 [rotate_sampler:NeedComms:C:A_Timeout:A](IMPORTANT): first GPS update timeout 2017-09-06T16:29:22.545Z,1504715362.545 [rotate_sampler:NeedComms:C:A_Timeout:A] Stopped 2017-09-06T16:29:22.546Z,1504715362.546 [rotate_sampler:NeedComms:C:A_Timeout](INFO): Completed rotate_sampler:NeedComms:C:A_Timeout 2017-09-06T16:29:22.546Z,1504715362.546 [rotate_sampler:NeedComms:C] Stopped 2017-09-06T16:29:22.546Z,1504715362.546 [rotate_sampler:NeedComms:D] Running Loop=1 2017-09-06T16:29:22.932Z,1504715362.932 [rotate_sampler:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2017-09-06T16:29:24.890Z,1504715364.890 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:27.094Z,1504715367.094 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:27.607Z,1504715367.607 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:27.851Z,1504715367.851 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20170905T231733/Courier0412.lzma 2017-09-06T16:29:28.644Z,1504715368.644 [DataOverHttps](INFO): Moved sent file to Logs/20170905T231733/Courier0412.lzma.bak 2017-09-06T16:29:28.644Z,1504715368.644 [DataOverHttps](INFO): SBD MOMSN=5072592 2017-09-06T16:29:30.860Z,1504715370.860 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:35.671Z,1504715375.671 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:36.103Z,1504715376.103 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:39.084Z,1504715379.084 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20170906T162656/Courier0000.lzma 2017-09-06T16:29:39.221Z,1504715379.221 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:39.437Z,1504715379.437 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:39.864Z,1504715379.864 [DataOverHttps](INFO): Moved sent file to Logs/20170906T162656/Courier0000.lzma.bak 2017-09-06T16:29:39.865Z,1504715379.865 [DataOverHttps](INFO): SBD MOMSN=5072594 2017-09-06T16:29:41.005Z,1504715381.005 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:45.592Z,1504715385.592 [PNI_TCM](ERROR): CRC does not match. Expected:0x23923 got:0x53017 2017-09-06T16:29:46.252Z,1504715386.252 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:47.610Z,1504715387.610 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:48.654Z,1504715388.654 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:50.207Z,1504715390.207 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20170906T162656/Courier0004.lzma 2017-09-06T16:29:50.222Z,1504715390.222 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:50.994Z,1504715390.994 [DataOverHttps](INFO): Moved sent file to Logs/20170906T162656/Courier0004.lzma.bak 2017-09-06T16:29:50.994Z,1504715390.994 [DataOverHttps](INFO): SBD MOMSN=5072597 2017-09-06T16:29:55.463Z,1504715395.463 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:55.875Z,1504715395.875 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:57.033Z,1504715397.033 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:29:58.083Z,1504715398.083 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:01.554Z,1504715401.554 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.554Z,1504715401.554 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.554Z,1504715401.554 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.555Z,1504715401.555 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.555Z,1504715401.555 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.556Z,1504715401.556 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.556Z,1504715401.556 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.557Z,1504715401.557 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.557Z,1504715401.557 [PNI_TCM](ERROR): CRC does not match. Expected:0x47390 got:0x17185 2017-09-06T16:30:01.770Z,1504715401.770 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20170905T231733/Express0413.lzma 2017-09-06T16:30:02.554Z,1504715402.554 [DataOverHttps](INFO): Moved sent file to Logs/20170905T231733/Express0413.lzma.bak 2017-09-06T16:30:02.554Z,1504715402.554 [DataOverHttps](INFO): SBD MOMSN=5072599 2017-09-06T16:30:03.627Z,1504715403.627 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:07.811Z,1504715407.811 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:11.997Z,1504715411.997 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:13.183Z,1504715413.183 [DataOverHttps](INFO): Sending 895 bytes from file Logs/20170906T162656/Express0001.lzma 2017-09-06T16:30:13.963Z,1504715413.963 [DataOverHttps](INFO): Moved sent file to Logs/20170906T162656/Express0001.lzma.bak 2017-09-06T16:30:13.963Z,1504715413.963 [DataOverHttps](INFO): SBD MOMSN=5072602 2017-09-06T16:30:17.237Z,1504715417.237 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:18.296Z,1504715418.296 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:21.217Z,1504715421.217 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:23.839Z,1504715423.839 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:24.273Z,1504715424.273 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20170906T162656/Express0005.lzma 2017-09-06T16:30:25.064Z,1504715425.064 [DataOverHttps](INFO): Moved sent file to Logs/20170906T162656/Express0005.lzma.bak 2017-09-06T16:30:25.064Z,1504715425.064 [DataOverHttps](INFO): SBD MOMSN=5072627 2017-09-06T16:30:25.649Z,1504715425.649 [rotate_sampler:NeedComms:D] Stopped 2017-09-06T16:30:25.649Z,1504715425.649 [rotate_sampler:NeedComms:E] Running Loop=1 2017-09-06T16:30:25.983Z,1504715425.983 [rotate_sampler:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2017-09-06T16:30:27.498Z,1504715427.498 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:28.647Z,1504715428.647 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:32.838Z,1504715432.838 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:38.799Z,1504715438.799 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:39.857Z,1504715439.857 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:41.427Z,1504715441.427 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:44.248Z,1504715444.248 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:51.059Z,1504715451.059 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:53.679Z,1504715453.679 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:30:59.216Z,1504715459.216 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:02.256Z,1504715462.256 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:06.451Z,1504715466.451 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:08.439Z,1504715468.439 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:09.484Z,1504715469.484 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:15.662Z,1504715475.662 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:16.519Z,1504715476.519 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:20.281Z,1504715480.281 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:25.816Z,1504715485.816 [rotate_sampler:NeedComms:E](INFO): Timed out from 2017-09-06T16:30:25.6Z 2017-09-06T16:31:25.816Z,1504715485.816 [rotate_sampler:NeedComms:E:A_Timeout] Running Loop=1 2017-09-06T16:31:25.816Z,1504715485.816 [rotate_sampler:NeedComms:E:A_Timeout](DEBUG): Aggregate::initialize rotate_sampler:NeedComms:E:A_Timeout 2017-09-06T16:31:25.816Z,1504715485.816 [rotate_sampler:NeedComms:E:A_Timeout:A] Running Loop=1 2017-09-06T16:31:25.817Z,1504715485.817 [rotate_sampler:NeedComms:E:A_Timeout:A](IMPORTANT): second GPS update timeout 2017-09-06T16:31:25.831Z,1504715485.831 [rotate_sampler:NeedComms:E:A_Timeout:A] Stopped 2017-09-06T16:31:25.831Z,1504715485.831 [rotate_sampler:NeedComms:E:A_Timeout](INFO): Completed rotate_sampler:NeedComms:E:A_Timeout 2017-09-06T16:31:25.831Z,1504715485.831 [rotate_sampler:NeedComms:E] Stopped 2017-09-06T16:31:25.832Z,1504715485.832 [rotate_sampler:NeedComms](INFO): Completed rotate_sampler:NeedComms 2017-09-06T16:31:25.832Z,1504715485.832 [rotate_sampler:NeedComms] Stopped 2017-09-06T16:31:25.832Z,1504715485.832 [rotate_sampler:NeedComms](DEBUG): Aggregate::uninitialize rotate_sampler:NeedComms 2017-09-06T16:31:25.832Z,1504715485.832 [rotate_sampler:NeedComms:B.GoToSurface] Stopped 2017-09-06T16:31:25.832Z,1504715485.832 [rotate_sampler:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-09-06T16:31:26.139Z,1504715486.139 [rotate_sampler:StartingMission](INFO): Completed rotate_sampler:StartingMission 2017-09-06T16:31:26.139Z,1504715486.139 [rotate_sampler:StartingMission] Stopped 2017-09-06T16:31:26.139Z,1504715486.139 [rotate_sampler:StartingMission](DEBUG): Aggregate::uninitialize rotate_sampler:StartingMission 2017-09-06T16:31:26.140Z,1504715486.140 [rotate_sampler:L] Running Loop=1 2017-09-06T16:31:28.217Z,1504715488.217 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:31.048Z,1504715491.048 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:34.103Z,1504715494.103 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:34.124Z,1504715494.124 [rotate_sampler:L] Stopped 2017-09-06T16:31:34.125Z,1504715494.125 [rotate_sampler:O] Running Loop=1 2017-09-06T16:31:34.308Z,1504715494.308 [rotate_sampler:O] Stopped 2017-09-06T16:31:34.309Z,1504715494.309 [rotate_sampler:P] Running Loop=1 2017-09-06T16:31:34.658Z,1504715494.658 [rotate_sampler:P] Stopped 2017-09-06T16:31:34.658Z,1504715494.658 [rotate_sampler:Cast] Running Loop=1 2017-09-06T16:31:34.658Z,1504715494.658 [rotate_sampler:Cast](DEBUG): Aggregate::initialize rotate_sampler:Cast 2017-09-06T16:31:34.658Z,1504715494.658 [rotate_sampler:Cast:A] Running Loop=1 2017-09-06T16:31:34.828Z,1504715494.828 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:35.047Z,1504715495.047 [rotate_sampler:Cast:A] Stopped 2017-09-06T16:31:35.047Z,1504715495.047 [rotate_sampler:Cast:Depth01] Running Loop=1 2017-09-06T16:31:35.047Z,1504715495.047 [rotate_sampler:Cast:Depth01](DEBUG): Aggregate::initialize rotate_sampler:Cast:Depth01 2017-09-06T16:31:35.533Z,1504715495.533 [rotate_sampler:SampleWithinEnvelopesWrapper] Running Loop=1 2017-09-06T16:31:35.548Z,1504715495.548 [rotate_sampler:SampleWithinEnvelopesWrapper](DEBUG): Aggregate::initialize rotate_sampler:SampleWithinEnvelopesWrapper 2017-09-06T16:31:35.548Z,1504715495.548 [rotate_sampler:SampleWithinEnvelopesWrapper:A] Running Loop=1 2017-09-06T16:31:35.550Z,1504715495.550 [rotate_sampler:SampleWithinEnvelopesWrapper:A] Stopped 2017-09-06T16:31:35.551Z,1504715495.551 [rotate_sampler:SampleWithinEnvelopesWrapper:SampleWithinEnvelopes] Running Loop=1 2017-09-06T16:31:35.551Z,1504715495.551 [rotate_sampler:SampleWithinEnvelopesWrapper:SampleWithinEnvelopes](DEBUG): Aggregate::initialize rotate_sampler:SampleWithinEnvelopesWrapper:SampleWithinEnvelopes 2017-09-06T16:31:35.870Z,1504715495.870 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:35.879Z,1504715495.879 [rotate_sampler:SampleAtDepth] Running Loop=1 2017-09-06T16:31:35.879Z,1504715495.879 [rotate_sampler:SampleAtDepth](DEBUG): Aggregate::initialize rotate_sampler:SampleAtDepth 2017-09-06T16:31:35.879Z,1504715495.879 [rotate_sampler:SampleAtDepth:B.Pitch] Running Loop=1 2017-09-06T16:31:35.879Z,1504715495.879 [rotate_sampler:SampleAtDepth:B.Pitch](DEBUG): Initialize. 2017-09-06T16:31:35.879Z,1504715495.879 [rotate_sampler:SampleAtDepth:A] Running Loop=1 2017-09-06T16:31:35.888Z,1504715495.888 [rotate_sampler:SampleAtDepth:A](INFO): Moving to 0.000000 m 2017-09-06T16:31:35.892Z,1504715495.892 [rotate_sampler:SampleAtDepth:A] Stopped 2017-09-06T16:31:41.835Z,1504715501.835 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:45.505Z,1504715505.505 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:49.751Z,1504715509.751 [CommandLine](IMPORTANT): got command show stack 2017-09-06T16:31:49.751Z,1504715509.751 [CommandLine](IMPORTANT): Behavior Stack: 2017-09-06T16:31:49.752Z,1504715509.752 [rotate_sampler:Science](IMPORTANT): Priority 0: rotate_sampler:Science:A 2017-09-06T16:31:49.753Z,1504715509.753 [rotate_sampler:Science](IMPORTANT): Priority 1: rotate_sampler:Science:C 2017-09-06T16:31:49.753Z,1504715509.753 [rotate_sampler:Science](IMPORTANT): Priority 2: rotate_sampler:Science:F 2017-09-06T16:31:49.753Z,1504715509.753 [rotate_sampler:Science:PeakDetectChl](IMPORTANT): Priority 3: rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth 2017-09-06T16:31:49.754Z,1504715509.754 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 4: rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope 2017-09-06T16:31:49.754Z,1504715509.754 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 5: rotate_sampler:StandardEnvelopes:B.DepthEnvelope 2017-09-06T16:31:49.754Z,1504715509.754 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 6: rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope 2017-09-06T16:31:49.754Z,1504715509.754 [rotate_sampler](IMPORTANT): Priority 7: rotate_sampler:I.Pitch 2017-09-06T16:31:49.755Z,1504715509.755 [rotate_sampler](IMPORTANT): Priority 8: rotate_sampler:J.Buoyancy 2017-09-06T16:31:49.755Z,1504715509.755 [rotate_sampler](IMPORTANT): Priority 9: rotate_sampler:K.SetSpeed 2017-09-06T16:31:49.756Z,1504715509.756 [rotate_sampler](IMPORTANT): Priority 10: rotate_sampler:M.Point 2017-09-06T16:31:49.757Z,1504715509.757 [rotate_sampler:SampleAtDepth](IMPORTANT): Priority 11: rotate_sampler:SampleAtDepth:B.Pitch 2017-09-06T16:31:50.416Z,1504715510.416 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:54.078Z,1504715514.078 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:57.219Z,1504715517.219 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:31:58.701Z,1504715518.701 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:00.897Z,1504715520.897 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:02.712Z,1504715522.712 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:09.282Z,1504715529.282 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:16.490Z,1504715536.490 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:17.643Z,1504715537.643 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:18.489Z,1504715538.489 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:18.752Z,1504715538.752 [CommandLine](IMPORTANT): got command get depth 2017-09-06T16:32:18.752Z,1504715538.752 [CommandLine](IMPORTANT): depth 0.347100 m 2017-09-06T16:32:21.305Z,1504715541.305 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:22.880Z,1504715542.880 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:24.022Z,1504715544.022 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:26.697Z,1504715546.697 [CommandLine](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.000000 meter 2017-09-06T16:32:26.699Z,1504715546.699 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread. 2017-09-06T16:32:29.271Z,1504715549.271 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:30.353Z,1504715550.353 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-09-06T16:32:30.478Z,1504715550.478 [rotate_sampler:SampleAtDepth:C] Running Loop=1 2017-09-06T16:32:30.869Z,1504715550.869 [rotate_sampler:SampleAtDepth:C](INFO): At 0.000000 m , settling for 0.000000 min 2017-09-06T16:32:30.871Z,1504715550.871 [rotate_sampler:SampleAtDepth:C] Stopped 2017-09-06T16:32:30.871Z,1504715550.871 [rotate_sampler:SampleAtDepth:D.Wait] Running Loop=1 2017-09-06T16:32:30.871Z,1504715550.871 [rotate_sampler:SampleAtDepth:D.Wait](DEBUG): Initialize Wait Component. 2017-09-06T16:32:31.255Z,1504715551.255 [rotate_sampler:SampleAtDepth:D.Wait](INFO): Done Waiting. 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:D.Wait] Stopped 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:SampleWrapper] Running Loop=1 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize rotate_sampler:SampleAtDepth:SampleWrapper 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler] Running Loop=1 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::initialize rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1 2017-09-06T16:32:31.270Z,1504715551.270 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Running Loop=1 2017-09-06T16:32:31.271Z,1504715551.271 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Running Loop=1 2017-09-06T16:32:31.670Z,1504715551.670 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler](DEBUG): Initialize ReadDataComponent to sense CANONSampler.sampling 2017-09-06T16:32:31.672Z,1504715551.672 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Running Loop=1 2017-09-06T16:32:31.673Z,1504715551.673 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1 2017-09-06T16:32:32.368Z,1504715552.368 [CANONSampler](INFO): Powering up 2017-09-06T16:32:33.456Z,1504715553.456 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:38.905Z,1504715558.905 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:39.422Z,1504715559.422 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:43.569Z,1504715563.569 [CANONSampler](IMPORTANT): Rotation Only! 2017-09-06T16:32:43.966Z,1504715563.966 [CANONSampler](IMPORTANT): UI[1]=0 2017-09-06T16:32:43.967Z,1504715563.967 [CANONSampler](IMPORTANT): Saving.... DONE 2017-09-06T16:32:43.967Z,1504715563.967 [CANONSampler](IMPORTANT): 1.2 2017-09-06T16:32:43.967Z,1504715563.967 [CANONSampler](IMPORTANT): S>R 2017-09-06T16:32:43.968Z,1504715563.968 [CANONSampler](IMPORTANT): UM=2 2017-09-06T16:32:43.968Z,1504715563.968 [CANONSampler](IMPORTANT): JV=750 2017-09-06T16:32:43.968Z,1504715563.968 [CANONSampler](IMPORTANT): MO=1 2017-09-06T16:32:44.761Z,1504715564.761 [CANONSampler](IMPORTANT): MO=1 2017-09-06T16:32:45.962Z,1504715565.962 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=707 2017-09-06T16:32:46.775Z,1504715566.775 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=1429 2017-09-06T16:32:47.961Z,1504715567.961 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=2152 2017-09-06T16:32:48.765Z,1504715568.765 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=2876 2017-09-06T16:32:49.967Z,1504715569.967 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=3600 2017-09-06T16:32:50.762Z,1504715570.762 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=4323 2017-09-06T16:32:51.966Z,1504715571.966 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=5046 2017-09-06T16:32:52.760Z,1504715572.760 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=5769 2017-09-06T16:32:53.448Z,1504715573.448 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:53.963Z,1504715573.963 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=6494 2017-09-06T16:32:53.963Z,1504715573.963 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=7220 2017-09-06T16:32:54.362Z,1504715574.362 [CANONSampler](IMPORTANT): Saving.... DONE 2017-09-06T16:32:55.163Z,1504715575.163 [CANONSampler](IMPORTANT): HOMINGMO=1, MF=0, PX=93 2017-09-06T16:32:55.864Z,1504715575.864 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:56.369Z,1504715576.369 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=814 2017-09-06T16:32:57.162Z,1504715577.162 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=1537 2017-09-06T16:32:58.362Z,1504715578.362 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=2263 2017-09-06T16:32:58.484Z,1504715578.484 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:32:59.163Z,1504715579.163 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=2989 2017-09-06T16:33:00.361Z,1504715580.361 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=3715 2017-09-06T16:33:01.173Z,1504715581.173 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=4442 2017-09-06T16:33:02.361Z,1504715582.361 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=5169 2017-09-06T16:33:03.561Z,1504715583.561 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=5896 2017-09-06T16:33:04.025Z,1504715584.025 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:04.371Z,1504715584.371 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=6622 2017-09-06T16:33:05.564Z,1504715585.564 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=7347 2017-09-06T16:33:06.363Z,1504715586.363 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=8073 2017-09-06T16:33:07.562Z,1504715587.562 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=8799 2017-09-06T16:33:08.361Z,1504715588.361 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=9525 2017-09-06T16:33:09.563Z,1504715589.563 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=10253 2017-09-06T16:33:10.360Z,1504715590.360 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=10982 2017-09-06T16:33:11.562Z,1504715591.562 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=11710 2017-09-06T16:33:12.368Z,1504715592.368 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=12436 2017-09-06T16:33:13.562Z,1504715593.562 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=13163 2017-09-06T16:33:14.761Z,1504715594.761 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=13889 2017-09-06T16:33:15.567Z,1504715595.567 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=14616 2017-09-06T16:33:16.762Z,1504715596.762 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=15343 2017-09-06T16:33:17.566Z,1504715597.566 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=16070 2017-09-06T16:33:17.847Z,1504715597.847 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:18.769Z,1504715598.769 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=16797 2017-09-06T16:33:19.563Z,1504715599.563 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=17524 2017-09-06T16:33:20.762Z,1504715600.762 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=18251 2017-09-06T16:33:21.562Z,1504715601.562 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=18981 2017-09-06T16:33:22.760Z,1504715602.760 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=19711 2017-09-06T16:33:23.568Z,1504715603.568 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=20440 2017-09-06T16:33:24.763Z,1504715604.763 [CANONSampler](IMPORTANT): MO=1, MF=0, PX=20970 2017-09-06T16:33:25.707Z,1504715605.707 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:25.960Z,1504715605.960 [CANONSampler](IMPORTANT): MO=0, MF=0, PX=20970 2017-09-06T16:33:25.961Z,1504715605.961 [CANONSampler](IMPORTANT): DONE with MO=0, MF=0 2017-09-06T16:33:25.961Z,1504715605.961 [CANONSampler](IMPORTANT): Rotation err_code=0 2017-09-06T16:33:26.111Z,1504715606.111 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Stopped 2017-09-06T16:33:26.111Z,1504715606.111 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:D] Running Loop=1 2017-09-06T16:33:26.460Z,1504715606.460 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:D](IMPORTANT): CANONSampler sampling at 0.000000 m in 20.284326 degC water with 0.086363 ug/l chlorophyll fluorescence. 2017-09-06T16:33:26.461Z,1504715606.461 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:D] Stopped 2017-09-06T16:33:26.461Z,1504715606.461 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E] Running Loop=1 2017-09-06T16:33:26.768Z,1504715606.768 [CANONSampler](INFO): Powering down 2017-09-06T16:33:27.474Z,1504715607.474 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:30.585Z,1504715610.585 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E](DEBUG): Initialize ReadDataComponent to sense CANONSampler.sample_number 2017-09-06T16:33:33.553Z,1504715613.553 [CommandLine](IMPORTANT): got command show stack 2017-09-06T16:33:33.553Z,1504715613.553 [CommandLine](IMPORTANT): Behavior Stack: 2017-09-06T16:33:33.561Z,1504715613.561 [rotate_sampler:Science](IMPORTANT): Priority 0: rotate_sampler:Science:A 2017-09-06T16:33:33.561Z,1504715613.561 [rotate_sampler:Science](IMPORTANT): Priority 1: rotate_sampler:Science:C 2017-09-06T16:33:33.562Z,1504715613.562 [rotate_sampler:Science](IMPORTANT): Priority 2: rotate_sampler:Science:F 2017-09-06T16:33:33.562Z,1504715613.562 [rotate_sampler:Science:PeakDetectChl](IMPORTANT): Priority 3: rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth 2017-09-06T16:33:33.563Z,1504715613.563 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 4: rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope 2017-09-06T16:33:33.563Z,1504715613.563 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 5: rotate_sampler:StandardEnvelopes:B.DepthEnvelope 2017-09-06T16:33:33.563Z,1504715613.563 [rotate_sampler:StandardEnvelopes](IMPORTANT): Priority 6: rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope 2017-09-06T16:33:33.564Z,1504715613.564 [rotate_sampler](IMPORTANT): Priority 7: rotate_sampler:I.Pitch 2017-09-06T16:33:33.565Z,1504715613.565 [rotate_sampler](IMPORTANT): Priority 8: rotate_sampler:J.Buoyancy 2017-09-06T16:33:33.565Z,1504715613.565 [rotate_sampler](IMPORTANT): Priority 9: rotate_sampler:K.SetSpeed 2017-09-06T16:33:33.565Z,1504715613.565 [rotate_sampler](IMPORTANT): Priority 10: rotate_sampler:M.Point 2017-09-06T16:33:33.566Z,1504715613.566 [rotate_sampler:SampleAtDepth](IMPORTANT): Priority 11: rotate_sampler:SampleAtDepth:B.Pitch 2017-09-06T16:33:33.566Z,1504715613.566 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler](IMPORTANT): Priority 12: rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:A 2017-09-06T16:33:33.567Z,1504715613.567 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler](IMPORTANT): Priority 13: rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:B 2017-09-06T16:33:33.567Z,1504715613.567 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler](IMPORTANT): Priority 14: rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E 2017-09-06T16:33:35.863Z,1504715615.863 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:36.698Z,1504715616.698 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2017-09-06T16:33:36.909Z,1504715616.909 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:40.462Z,1504715620.462 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:42.299Z,1504715622.299 [NAL9602](INFO): Powering down 2017-09-06T16:33:43.912Z,1504715623.912 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:50.823Z,1504715630.823 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:58.463Z,1504715638.463 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:33:58.884Z,1504715638.884 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:00.037Z,1504715640.037 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:00.462Z,1504715640.462 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:05.698Z,1504715645.698 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:05.907Z,1504715645.907 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:13.434Z,1504715653.434 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:16.475Z,1504715656.475 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:18.054Z,1504715658.054 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:21.924Z,1504715661.924 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:25.690Z,1504715665.690 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:31.038Z,1504715671.038 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:32.926Z,1504715672.926 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:33.442Z,1504715673.442 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:35.266Z,1504715675.266 [CTD_NeilBrown](ERROR): Salinity reading out of range: 27.206400 psu 2017-09-06T16:34:35.274Z,1504715675.274 [CTD_NeilBrown](INFO): some bad data, not updating bins 2017-09-06T16:34:37.316Z,1504715677.316 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:43.077Z,1504715683.077 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:44.640Z,1504715684.640 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:48.310Z,1504715688.310 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:49.668Z,1504715689.668 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:50.092Z,1504715690.092 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:51.238Z,1504715691.238 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:53.858Z,1504715693.858 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:34:59.091Z,1504715699.091 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:00.666Z,1504715700.666 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:03.290Z,1504715703.290 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:07.680Z,1504715707.680 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:16.062Z,1504715716.062 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:17.111Z,1504715717.111 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:23.073Z,1504715723.073 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:27.884Z,1504715727.884 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:36.474Z,1504715736.474 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:39.094Z,1504715739.094 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:40.664Z,1504715740.664 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:49.458Z,1504715749.458 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:54.502Z,1504715754.502 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:55.108Z,1504715755.108 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:35:58.256Z,1504715758.256 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:02.651Z,1504715762.651 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:06.730Z,1504715766.730 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:07.887Z,1504715767.887 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:10.508Z,1504715770.508 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:14.895Z,1504715774.895 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:16.469Z,1504715776.469 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:17.519Z,1504715777.519 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:19.089Z,1504715779.089 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:20.656Z,1504715780.656 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:31.341Z,1504715791.341 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:32.911Z,1504715792.911 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:37.098Z,1504715797.098 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:37.314Z,1504715797.314 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:39.293Z,1504715799.293 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:43.684Z,1504715803.684 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:44.734Z,1504715804.734 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:47.875Z,1504715807.875 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:49.453Z,1504715809.453 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:51.553Z,1504715811.553 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:36:59.082Z,1504715819.082 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:01.284Z,1504715821.284 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:04.341Z,1504715824.341 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:09.878Z,1504715829.878 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:12.478Z,1504715832.478 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:17.097Z,1504715837.097 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:18.463Z,1504715838.463 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:24.741Z,1504715844.741 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:25.258Z,1504715845.258 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:32.068Z,1504715852.068 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:32.698Z,1504715852.698 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:49.650Z,1504715869.650 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:53.951Z,1504715873.951 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:54.260Z,1504715874.260 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:37:59.292Z,1504715879.292 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:04.736Z,1504715884.736 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:07.970Z,1504715887.970 [CBIT](DEBUG): EFC running - data check-sum false 2017-09-06T16:38:08.923Z,1504715888.923 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:14.456Z,1504715894.456 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:15.505Z,1504715895.505 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:15.926Z,1504715895.926 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:21.275Z,1504715901.274 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:25.149Z,1504715905.149 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:25.465Z,1504715905.465 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:29.135Z,1504715909.135 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:39.287Z,1504715919.287 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:46.935Z,1504715926.935 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:50.064Z,1504715930.064 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:51.114Z,1504715931.114 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:55.309Z,1504715935.309 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:56.354Z,1504715936.354 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:38:58.549Z,1504715938.549 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:00.745Z,1504715940.745 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:03.906Z,1504715943.906 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:04.940Z,1504715944.940 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:06.510Z,1504715946.510 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:10.476Z,1504715950.476 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:17.292Z,1504715957.292 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:18.770Z,1504715958.770 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:19.062Z,1504715959.062 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:26.794Z,1504715966.794 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E](INFO): Timed out from 2017-09-06T16:33:26.5Z 2017-09-06T16:39:26.798Z,1504715966.798 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout] Running Loop=1 2017-09-06T16:39:26.798Z,1504715966.798 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout](DEBUG): Aggregate::initialize rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout 2017-09-06T16:39:26.799Z,1504715966.799 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:A] Running Loop=1 2017-09-06T16:39:26.799Z,1504715966.799 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:A](IMPORTANT): Timed out sampling with CANONSampler. Stopping mission. 2017-09-06T16:39:26.800Z,1504715966.800 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:A] Stopped 2017-09-06T16:39:26.800Z,1504715966.800 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute] Running Loop=1 2017-09-06T16:39:26.914Z,1504715966.914 [CTD_NeilBrown](DEBUG): serial timeout 2017-09-06T16:39:27.177Z,1504715967.177 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](INFO): Executing command stop 2017-09-06T16:39:27.186Z,1504715967.186 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute] Stopped 2017-09-06T16:39:27.186Z,1504715967.186 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout](INFO): Completed rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout 2017-09-06T16:39:27.186Z,1504715967.186 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:E] Stopped 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler](INFO): Completed rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler] Stopped 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::uninitialize rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Stopped 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Stopped 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP] Running Loop=1 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::initialize rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP 2017-09-06T16:39:27.192Z,1504715967.192 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Running Loop=1 2017-09-06T16:39:27.257Z,1504715967.257 [CommandLine](IMPORTANT): got command stop 2017-09-06T16:39:27.257Z,1504715967.257 [CommandLine](IMPORTANT): Scheduling is paused 2017-09-06T16:39:27.258Z,1504715967.258 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop 2017-09-06T16:39:27.573Z,1504715967.573 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP] Stopped 2017-09-06T16:39:27.573Z,1504715967.573 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::uninitialize rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP 2017-09-06T16:39:27.573Z,1504715967.573 [rotate_sampler:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Stopped 2017-09-06T16:39:27.574Z,1504715967.574 [rotate_sampler:SampleAtDepth:SampleWrapper](INFO): Completed rotate_sampler:SampleAtDepth:SampleWrapper 2017-09-06T16:39:27.574Z,1504715967.574 [rotate_sampler:SampleAtDepth:SampleWrapper] Stopped 2017-09-06T16:39:27.574Z,1504715967.574 [rotate_sampler:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::uninitialize rotate_sampler:SampleAtDepth:SampleWrapper 2017-09-06T16:39:27.575Z,1504715967.575 [rotate_sampler:SampleAtDepth](INFO): Completed rotate_sampler:SampleAtDepth 2017-09-06T16:39:27.575Z,1504715967.575 [rotate_sampler:SampleAtDepth] Stopped 2017-09-06T16:39:27.587Z,1504715967.587 [rotate_sampler:SampleAtDepth](DEBUG): Aggregate::uninitialize rotate_sampler:SampleAtDepth 2017-09-06T16:39:27.587Z,1504715967.587 [rotate_sampler:SampleAtDepth:B.Pitch] Stopped 2017-09-06T16:39:27.622Z,1504715967.622 [MissionManager](INFO): MissionManager is completed. 2017-09-06T16:39:27.622Z,1504715967.622 [MissionManager](INFO): Uninitializing Mission rotate_sampler 2017-09-06T16:39:27.622Z,1504715967.622 [rotate_sampler] Stopped 2017-09-06T16:39:27.622Z,1504715967.622 [rotate_sampler](DEBUG): Aggregate::uninitialize rotate_sampler 2017-09-06T16:39:27.622Z,1504715967.622 [rotate_sampler:Science] Stopped 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science](DEBUG): Aggregate::uninitialize rotate_sampler:Science 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science:A] Stopped 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science:C] Stopped 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science:F] Stopped 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science:PeakDetectChl] Stopped 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize rotate_sampler:Science:PeakDetectChl 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2017-09-06T16:39:27.623Z,1504715967.623 [rotate_sampler:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2017-09-06T16:39:27.631Z,1504715967.631 [rotate_sampler:StandardEnvelopes] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:StandardEnvelopes](DEBUG): Aggregate::uninitialize rotate_sampler:StandardEnvelopes 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:I.Pitch] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:J.Buoyancy] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:K.SetSpeed] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:K.SetSpeed](DEBUG): Uninitialize. 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:M.Point] Stopped 2017-09-06T16:39:27.632Z,1504715967.632 [rotate_sampler:SampleWithinEnvelopesWrapper] Stopped 2017-09-06T16:39:27.633Z,1504715967.633 [rotate_sampler:SampleWithinEnvelopesWrapper](DEBUG): Aggregate::uninitialize rotate_sampler:SampleWithinEnvelopesWrapper 2017-09-06T16:39:27.633Z,1504715967.633 [rotate_sampler:SampleWithinEnvelopesWrapper:SampleWithinEnvelopes] Stopped 2017-09-06T16:39:27.633Z,1504715967.633 [rotate_sampler:SampleWithinEnvelopesWrapper:SampleWithinEnvelopes](DEBUG): Aggregate::uninitialize rotate_sampler:SampleWithinEnvelopesWrapper:SampleWithinEnvelopes 2017-09-06T16:39:27.633Z,1504715967.633 [rotate_sampler:Cast] Stopped 2017-09-06T16:39:27.633Z,1504715967.633 [rotate_sampler:Cast](DEBUG): Aggregate::uninitialize rotate_sampler:Cast 2017-09-06T16:39:27.633Z,1504715967.633 [rotate_sampler:Cast:Depth01] Stopped 2017-09-06T16:39:27.633Z,1504715967.633 [rotate_sampler:Cast:Depth01](DEBUG): Aggregate::uninitialize rotate_sampler:Cast:Depth01 2017-09-06T16:39:28.100Z,1504715968.100 [WetLabsBB2FL](INFO): Powering down 2017-09-06T16:39:31.157Z,1504715971.157 [MissionManager](IMPORTANT): Started mission Default 2017-09-06T16:39:31.157Z,1504715971.157 [Default] Running Loop=1 2017-09-06T16:39:31.157Z,1504715971.157 [Default](DEBUG): Aggregate::initialize Default 2017-09-06T16:39:31.158Z,1504715971.158 [Default:B.GoToSurface] Running Loop=1 2017-09-06T16:39:31.158Z,1504715971.158 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-09-06T16:39:31.158Z,1504715971.158 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-09-06T16:39:31.158Z,1504715971.158 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-09-06T16:39:31.159Z,1504715971.159 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-09-06T16:39:31.159Z,1504715971.159 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-09-06T16:39:31.159Z,1504715971.159 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-09-06T16:39:31.160Z,1504715971.160 [Default:A.Wait] Running Loop=1 2017-09-06T16:39:31.160Z,1504715971.160 [Default:A.Wait](DEBUG): Initialize Wait Component. 2017-09-06T16:39:31.358Z,1504715971.358 [Aanderaa_O2](INFO): Powering down 2017-09-06T16:39:44.502Z,1504715984.502 [Default:A.Wait](INFO): Done Waiting. 2017-09-06T16:39:44.502Z,1504715984.502 [Default:A.Wait] Stopped 2017-09-06T16:39:44.502Z,1504715984.502 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2017-09-06T16:39:44.882Z,1504715984.882 [Default:CheckIn] Running Loop=1 2017-09-06T16:39:44.882Z,1504715984.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-09-06T16:39:44.883Z,1504715984.883 [Default:CheckIn:Read_GPS] Running Loop=1 2017-09-06T16:39:45.645Z,1504715985.645 [NAL9602](INFO): Powering up 2017-09-06T16:39:56.841Z,1504715996.841 [NAL9602](INFO): NAL9602 initialized 2017-09-06T16:39:57.641Z,1504715997.641 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-09-06T16:39:57.641Z,1504715997.641 [NAL9602] Data Fault, FailCount= 1 2017-09-06T16:39:57.641Z,1504715997.641 [NAL9602](ERROR): Data Fault 2017-09-06T16:39:57.761Z,1504715997.761 [CBIT](ERROR): Data Fault in component: NAL9602 2017-09-06T16:39:58.039Z,1504715998.039 [NAL9602](INFO): Powering down 2017-09-06T16:39:58.930Z,1504715998.930 [CBIT](INFO): Clearing failed state for component NAL9602 2017-09-06T16:39:58.930Z,1504715998.930 [NAL9602] No Fault, FailCount= 1 2017-09-06T16:40:03.249Z,1504716003.249 [NAL9602](INFO): Powering up NAL9602 2017-09-06T16:40:14.441Z,1504716014.441 [NAL9602](INFO): NAL9602 initialized 2017-09-06T16:42:38.402Z,1504716158.402 [PNI_TCM](ERROR): CRC does not match. Expected:0x7653 got:0x7360 2017-09-06T16:42:40.003Z,1504716160.003 [PNI_TCM](ERROR): CRC does not match. Expected:0x10144 got:0x46835 2017-09-06T16:42:51.207Z,1504716171.207 [PNI_TCM](ERROR): CRC does not match. Expected:0x62193 got:0x49336