ìQ8>ÍÌ̼‡Y½¦›Ä»1¬¼ôýÔ<ôýÔ<ôýT¼¼t¾Å °½1¬¼P—½{®½ÍÌL=㥛=–C‹½œÄ ¾)\½ôýÔ¼ßO½P—½hB
2012-09-18T17:26:43.004Z,1347989203.004 [Reporter](INFO): DVL_micro.AdcpNumBins 35 count
2012-09-18T17:26:43.004Z,1347989203.004 [Reporter](INFO): DVL_micro.Beam1Good 1 count
2012-09-18T17:26:43.005Z,1347989203.005 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:26:43.016Z,1347989203.016 [Reporter](INFO): DVL_micro.durationOfLastRun 0.163500 s
2012-09-18T17:26:43.304Z,1347989203.304 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=259
2012-09-18T17:26:44.123Z,1347989204.123 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=44
2012-09-18T17:26:44.984Z,1347989204.984 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=310
2012-09-18T17:26:45.805Z,1347989205.805 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:26:45.976Z,1347989205.976 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:26:45.976Z,1347989205.977 [Reporter](INFO): DVL_micro.durationOfLastRun 3.321200 s
2012-09-18T17:26:46.489Z,1347989206.489 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063700 s
2012-09-18T17:26:46.743Z,1347989206.743 [DVL_micro](DEBUG): NQ1
2012-09-18T17:26:46.766Z,1347989206.766 [DVL_micro](DEBUG): ADCP
2012-09-18T17:26:46.958Z,1347989206.958 [Reporter](INFO): DVL_micro.platform_pitch_angle -9.210000 arcdeg
2012-09-18T17:26:46.959Z,1347989206.959 [Reporter](INFO): DVL_micro.AdcpXWaterVelocity ßO=B`e=–C‹Tã%>=
W>é&1>ôýÔ¼ìQ8½¼t¼
×£¼
×#¼oƒºX9´<ÙÎ÷½w¾Ÿ½ôýT¼¼t“½h‘m½oƒ=/]=+‡½¬¾/ݽ‹lç½+¾hB
2012-09-18T17:26:46.960Z,1347989206.960 [Reporter](INFO): DVL_micro.AdcpNumBins 34 count
2012-09-18T17:26:46.960Z,1347989206.960 [Reporter](INFO): DVL_micro.Beam1Good 1 count
2012-09-18T17:26:46.961Z,1347989206.961 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:26:46.961Z,1347989206.961 [Reporter](INFO): DVL_micro.durationOfLastRun 0.047300 s
2012-09-18T17:26:47.635Z,1347989207.635 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=75
2012-09-18T17:26:48.479Z,1347989208.480 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=148
2012-09-18T17:26:49.317Z,1347989209.317 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=156
2012-09-18T17:26:50.139Z,1347989210.139 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:26:50.534Z,1347989210.534 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:26:50.538Z,1347989210.538 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061900 s
2012-09-18T17:26:51.111Z,1347989211.111 [DVL_micro](DEBUG): NQ1
2012-09-18T17:26:51.139Z,1347989211.139 [DVL_micro](DEBUG): ADCP
2012-09-18T17:26:51.666Z,1347989211.666 [Reporter](INFO): DVL_micro.platform_pitch_angle -9.179999 arcdeg
2012-09-18T17:26:51.675Z,1347989211.675 [Reporter](INFO): DVL_micro.AdcpXWaterVelocity Év¾=B`e=o=j¼=Âõ=oƒ»X94½
×£¼1¬¼X9´<
2012-09-18T17:26:51.675Z,1347989211.675 [Reporter](INFO): DVL_micro.AdcpNumBins 34 count
2012-09-18T17:26:51.676Z,1347989211.676 [Reporter](INFO): DVL_micro.Beam1Good 1 count
2012-09-18T17:26:51.677Z,1347989211.677 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:26:51.678Z,1347989211.678 [Reporter](INFO): DVL_micro.durationOfLastRun 0.178500 s
2012-09-18T17:26:52.004Z,1347989212.003 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=83
2012-09-18T17:26:52.843Z,1347989212.843 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=91
2012-09-18T17:26:53.680Z,1347989213.680 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=68
2012-09-18T17:26:54.527Z,1347989214.527 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:26:55.036Z,1347989215.036 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:26:55.036Z,1347989215.036 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062000 s
2012-09-18T17:26:55.495Z,1347989215.495 [DVL_micro](DEBUG): NQ1
2012-09-18T17:26:55.529Z,1347989215.529 [DVL_micro](DEBUG): ADCP
2012-09-18T17:26:56.150Z,1347989216.150 [Reporter](INFO): DVL_micro.platform_pitch_angle -9.210000 arcdeg
2012-09-18T17:26:56.154Z,1347989216.154 [Reporter](INFO): DVL_micro.AdcpXWaterVelocity 1,=B`å<¦›D<㥽`åнƒÀ½¸…½ìQ8½
×#½žï'½Âõ¼¦›D¼P½–C½
×#½w¾Ÿ½–C½h‘í¼oƒºoƒºo½o»Âu=+=+‡==
×=
2012-09-18T17:26:56.163Z,1347989216.163 [Reporter](INFO): DVL_micro.AdcpNumBins 31 count
2012-09-18T17:26:56.164Z,1347989216.164 [Reporter](INFO): DVL_micro.Beam1Good 1 count
2012-09-18T17:26:56.165Z,1347989216.165 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:26:56.166Z,1347989216.165 [Reporter](INFO): DVL_micro.durationOfLastRun 0.091200 s
2012-09-18T17:26:56.403Z,1347989216.403 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=276
2012-09-18T17:26:57.227Z,1347989217.227 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=124
2012-09-18T17:26:58.059Z,1347989218.059 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=68
2012-09-18T17:26:58.908Z,1347989218.908 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:26:59.050Z,1347989219.050 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:26:59.535Z,1347989219.535 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063600 s
2012-09-18T17:26:59.835Z,1347989219.835 [DVL_micro](DEBUG): NQ1
2012-09-18T17:26:59.863Z,1347989219.863 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:00.005Z,1347989220.005 [Reporter](INFO): DVL_micro.platform_pitch_angle -9.260000 arcdeg
2012-09-18T17:27:00.005Z,1347989220.006 [Reporter](INFO): DVL_micro.AdcpXWaterVelocity #Ûy=X94=B`e-²>o<-²½
×£¼hB
2012-09-18T17:27:00.018Z,1347989220.018 [Reporter](INFO): DVL_micro.AdcpNumBins 32 count
2012-09-18T17:27:00.027Z,1347989220.027 [Reporter](INFO): DVL_micro.Beam1Good 1 count
2012-09-18T17:27:00.028Z,1347989220.028 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:27:00.028Z,1347989220.028 [Reporter](INFO): DVL_micro.durationOfLastRun 0.038400 s
2012-09-18T17:27:00.727Z,1347989220.727 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=86
2012-09-18T17:27:01.592Z,1347989221.592 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=383
2012-09-18T17:27:01.931Z,1347989221.931 [CommandLine](IMPORTANT): got command report clear
2012-09-18T17:27:02.403Z,1347989222.403 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=73
2012-09-18T17:27:03.239Z,1347989223.239 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:04.139Z,1347989224.139 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:04.162Z,1347989224.161 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:05.035Z,1347989225.035 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=94
2012-09-18T17:27:05.262Z,1347989225.263 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-09-18T17:27:05.263Z,1347989225.263 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-09-18T17:27:05.263Z,1347989225.263 [AHRS_sp3003D](ERROR): Communications Fault
2012-09-18T17:27:05.452Z,1347989225.452 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-09-18T17:27:05.619Z,1347989225.619 [CommandLine](IMPORTANT): got command show variable DVL
2012-09-18T17:27:05.623Z,1347989225.623 [CommandLine](INFO): Config/vehicle.DVL_micro_card (none)
2012-09-18T17:27:05.623Z,1347989225.623 [CommandLine](INFO): Config/vehicle.DVL_micro_uart (none)
2012-09-18T17:27:05.623Z,1347989225.624 [CommandLine](INFO): Config/vehicle.DVL_micro_baud (bit_per_second)
2012-09-18T17:27:05.627Z,1347989225.627 [CommandLine](INFO): Config/Sensor.DVL_micro_enable (bool)
2012-09-18T17:27:05.628Z,1347989225.628 [CommandLine](INFO): Config/Sensor.DVL_micro_useHardware (bool)
2012-09-18T17:27:05.628Z,1347989225.628 [CommandLine](INFO): Config/Sensor.DVL_micro_magDeviation (degree)
2012-09-18T17:27:05.628Z,1347989225.628 [CommandLine](INFO): Config/Sensor.DVL_micro_pitchOffset (degree)
2012-09-18T17:27:05.629Z,1347989225.629 [CommandLine](INFO): Config/Sensor.DVL_micro_rollOffset (degree)
2012-09-18T17:27:05.629Z,1347989225.629 [CommandLine](INFO): Config/Sensor.DVL_micro_power (watt)
2012-09-18T17:27:05.649Z,1347989225.649 [CommandLine](INFO): DVL_micro.height_above_sea_floor (meter)
2012-09-18T17:27:05.649Z,1347989225.649 [CommandLine](INFO): DVL_micro.platform_orientation (degree)
2012-09-18T17:27:05.649Z,1347989225.649 [CommandLine](INFO): DVL_micro.platform_magnetic_orientation (degree)
2012-09-18T17:27:05.650Z,1347989225.650 [CommandLine](INFO): DVL_micro.platform_pitch_angle (degree)
2012-09-18T17:27:05.651Z,1347989225.651 [CommandLine](INFO): DVL_micro.platform_roll_angle (degree)
2012-09-18T17:27:05.651Z,1347989225.651 [CommandLine](INFO): DVL_micro.platform_speed_wrt_ground (meter_per_second)
2012-09-18T17:27:05.651Z,1347989225.651 [CommandLine](INFO): DVL_micro.platform_speed_wrt_sea_water (meter_per_second)
2012-09-18T17:27:05.652Z,1347989225.652 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second)
2012-09-18T17:27:05.652Z,1347989225.652 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second)
2012-09-18T17:27:05.652Z,1347989225.652 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second)
2012-09-18T17:27:05.653Z,1347989225.653 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second)
2012-09-18T17:27:05.653Z,1347989225.653 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second)
2012-09-18T17:27:05.654Z,1347989225.654 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second)
2012-09-18T17:27:05.654Z,1347989225.654 [CommandLine](INFO): DVL_micro.Status (count)
2012-09-18T17:27:05.655Z,1347989225.655 [CommandLine](INFO): DVL_micro.Beam1Good (count)
2012-09-18T17:27:05.655Z,1347989225.655 [CommandLine](INFO): DVL_micro.Beam2Good (count)
2012-09-18T17:27:05.655Z,1347989225.655 [CommandLine](INFO): DVL_micro.Beam3Good (count)
2012-09-18T17:27:05.656Z,1347989225.656 [CommandLine](INFO): DVL_micro.Beam4Good (count)
2012-09-18T17:27:05.656Z,1347989225.656 [CommandLine](INFO): DVL_micro.Altitude1 (meter)
2012-09-18T17:27:05.656Z,1347989225.657 [CommandLine](INFO): DVL_micro.Altitude2 (meter)
2012-09-18T17:27:05.657Z,1347989225.657 [CommandLine](INFO): DVL_micro.Altitude3 (meter)
2012-09-18T17:27:05.657Z,1347989225.657 [CommandLine](INFO): DVL_micro.Altitude4 (meter)
2012-09-18T17:27:05.658Z,1347989225.658 [CommandLine](INFO): DVL_micro.BottomVelocityFlag (count)
2012-09-18T17:27:05.658Z,1347989225.658 [CommandLine](INFO): DVL_micro.WaterVelocityFlag (count)
2012-09-18T17:27:05.667Z,1347989225.667 [CommandLine](INFO): DVL_micro.Beam1RSSI (decibel)
2012-09-18T17:27:05.667Z,1347989225.667 [CommandLine](INFO): DVL_micro.Beam2RSSI (decibel)
2012-09-18T17:27:05.668Z,1347989225.668 [CommandLine](INFO): DVL_micro.Beam3RSSI (decibel)
2012-09-18T17:27:05.668Z,1347989225.668 [CommandLine](INFO): DVL_micro.Beam4RSSI (decibel)
2012-09-18T17:27:05.668Z,1347989225.668 [CommandLine](INFO): DVL_micro.RSSIFilterLength (count)
2012-09-18T17:27:05.669Z,1347989225.669 [CommandLine](INFO): DVL_micro.RSSIPoints (count)
2012-09-18T17:27:05.669Z,1347989225.669 [CommandLine](INFO): DVL_micro.AdcpNumBins (count)
2012-09-18T17:27:05.669Z,1347989225.669 [CommandLine](INFO): DVL_micro.AdcpStartDepth (meter)
2012-09-18T17:27:05.670Z,1347989225.670 [CommandLine](INFO): DVL_micro.AdcpXWaterVelocity (meter_per_second)
2012-09-18T17:27:05.699Z,1347989225.699 [CommandLine](INFO): DVL_micro.AdcpYWaterVelocity (meter_per_second)
2012-09-18T17:27:05.700Z,1347989225.700 [CommandLine](INFO): DVL_micro.AdcpZWaterVelocity (meter_per_second)
2012-09-18T17:27:05.702Z,1347989225.702 [CommandLine](INFO): DVL_micro.component_voltage (volt)
2012-09-18T17:27:05.715Z,1347989225.715 [CommandLine](INFO): DVL_micro.component_avgVoltage (volt)
2012-09-18T17:27:05.715Z,1347989225.715 [CommandLine](INFO): DVL_micro.component_current (milliampere)
2012-09-18T17:27:05.716Z,1347989225.716 [CommandLine](INFO): DVL_micro.component_avgCurrent (milliampere)
2012-09-18T17:27:05.717Z,1347989225.717 [CommandLine](INFO): DVL_micro.durationOfLastRun (second)
2012-09-18T17:27:05.759Z,1347989225.759 [CommandLine](INFO): science_to_67_70:Science.EnabledDVL (none)
2012-09-18T17:27:05.891Z,1347989225.891 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=295
2012-09-18T17:27:06.715Z,1347989226.715 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=47
2012-09-18T17:27:07.276Z,1347989227.276 [AHRS_sp3003D](INFO): Powering down
2012-09-18T17:27:07.521Z,1347989227.521 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-18T17:27:07.521Z,1347989227.522 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-18T17:27:07.543Z,1347989227.543 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:08.459Z,1347989228.459 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:08.516Z,1347989228.516 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:08.960Z,1347989228.960 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-18T17:27:09.343Z,1347989229.343 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=46
2012-09-18T17:27:10.192Z,1347989230.192 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=153
2012-09-18T17:27:11.015Z,1347989231.015 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=1
2012-09-18T17:27:11.871Z,1347989231.871 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:12.755Z,1347989232.755 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:12.786Z,1347989232.786 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:13.661Z,1347989233.661 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=224
2012-09-18T17:27:14.500Z,1347989234.500 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=264
2012-09-18T17:27:15.319Z,1347989235.319 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=81
2012-09-18T17:27:16.151Z,1347989236.151 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:17.091Z,1347989237.091 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:17.131Z,1347989237.131 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:17.999Z,1347989237.999 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=225
2012-09-18T17:27:18.827Z,1347989238.827 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=137
2012-09-18T17:27:19.683Z,1347989239.684 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=338
2012-09-18T17:27:20.504Z,1347989240.504 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:21.330Z,1347989241.330 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:21.365Z,1347989241.365 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:22.231Z,1347989242.231 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=179
2012-09-18T17:27:23.055Z,1347989243.055 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=28
2012-09-18T17:27:23.919Z,1347989243.919 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=325
2012-09-18T17:27:24.727Z,1347989244.727 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:25.730Z,1347989245.730 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:25.759Z,1347989245.759 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:26.627Z,1347989246.627 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=75
2012-09-18T17:27:27.476Z,1347989247.476 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=179
2012-09-18T17:27:28.309Z,1347989248.309 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=124
2012-09-18T17:27:29.143Z,1347989249.143 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:30.107Z,1347989250.107 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:30.134Z,1347989250.134 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:30.999Z,1347989250.999 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=54
2012-09-18T17:27:31.842Z,1347989251.842 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=94
2012-09-18T17:27:32.672Z,1347989252.672 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=6
2012-09-18T17:27:33.510Z,1347989253.510 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:34.408Z,1347989254.408 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:34.434Z,1347989254.434 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:35.296Z,1347989255.296 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=59
2012-09-18T17:27:36.079Z,1347989256.079 [CommandLine](IMPORTANT): got command report touch DVL_micro.RSSIPoints
2012-09-18T17:27:36.080Z,1347989256.080 [CommandLine](IMPORTANT): got command report touch DVL_micro.component_avgCurrent
2012-09-18T17:27:36.081Z,1347989256.081 [CommandLine](IMPORTANT): got command report touch DVL_micro.component_current
2012-09-18T17:27:36.127Z,1347989256.127 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=3
2012-09-18T17:27:36.977Z,1347989256.977 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=107
2012-09-18T17:27:37.804Z,1347989257.804 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:38.258Z,1347989258.258 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:27:38.638Z,1347989258.638 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:38.670Z,1347989258.670 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:38.780Z,1347989258.780 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:27:38.781Z,1347989258.781 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:27:39.531Z,1347989259.531 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=51
2012-09-18T17:27:40.401Z,1347989260.401 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=411
2012-09-18T17:27:41.206Z,1347989261.206 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=36
2012-09-18T17:27:42.047Z,1347989262.047 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:42.301Z,1347989262.300 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:27:43.051Z,1347989263.051 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:43.110Z,1347989263.110 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:43.285Z,1347989263.285 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:27:43.285Z,1347989263.286 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:27:43.951Z,1347989263.951 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=167
2012-09-18T17:27:44.780Z,1347989264.780 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=47
2012-09-18T17:27:45.634Z,1347989265.634 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=215
2012-09-18T17:27:46.459Z,1347989266.459 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:46.742Z,1347989266.742 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:27:47.230Z,1347989267.230 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:47.261Z,1347989267.261 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:47.855Z,1347989267.855 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:27:47.856Z,1347989267.856 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:27:48.131Z,1347989268.131 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=195
2012-09-18T17:27:48.951Z,1347989268.951 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=11
2012-09-18T17:27:49.791Z,1347989269.791 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=51
2012-09-18T17:27:50.643Z,1347989270.643 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:50.984Z,1347989270.984 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:27:51.643Z,1347989271.643 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:51.669Z,1347989271.669 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:52.135Z,1347989272.135 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:27:52.135Z,1347989272.135 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:27:52.535Z,1347989272.535 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=103
2012-09-18T17:27:53.384Z,1347989273.384 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=207
2012-09-18T17:27:54.207Z,1347989274.207 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=56
2012-09-18T17:27:55.059Z,1347989275.059 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:55.505Z,1347989275.505 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:27:56.026Z,1347989276.026 [DVL_micro](DEBUG): NQ1
2012-09-18T17:27:56.075Z,1347989276.075 [DVL_micro](DEBUG): ADCP
2012-09-18T17:27:56.557Z,1347989276.557 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:27:56.558Z,1347989276.558 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:27:56.931Z,1347989276.931 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=197
2012-09-18T17:27:57.761Z,1347989277.761 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=109
2012-09-18T17:27:58.596Z,1347989278.596 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=85
2012-09-18T17:27:59.431Z,1347989279.431 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:27:59.979Z,1347989279.979 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:00.015Z,1347989280.015 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:00.024Z,1347989280.024 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:00.500Z,1347989280.500 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=278
2012-09-18T17:28:00.681Z,1347989280.681 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:00.682Z,1347989280.682 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:00.923Z,1347989280.923 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=31
2012-09-18T17:28:01.368Z,1347989281.368 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=7
2012-09-18T17:28:01.827Z,1347989281.827 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:28:01.963Z,1347989281.963 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=168
2012-09-18T17:28:02.011Z,1347989282.011 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92
2012-09-18T17:28:02.059Z,1347989282.059 [Reporter](INFO): DVL_micro.RSSIPoints 2556 count
2012-09-18T17:28:02.068Z,1347989282.068 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=113
2012-09-18T17:28:02.115Z,1347989282.115 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-18T17:28:02.405Z,1347989282.405 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=88
2012-09-18T17:28:02.511Z,1347989282.511 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=124
2012-09-18T17:28:02.608Z,1347989282.608 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-09-18T17:28:02.707Z,1347989282.707 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-18T17:28:02.777Z,1347989282.777 [Reporter](INFO): DVL_micro.RSSIPoints 590 count
2012-09-18T17:28:03.231Z,1347989283.231 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:03.253Z,1347989283.253 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:03.723Z,1347989283.723 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=14
2012-09-18T17:28:04.183Z,1347989284.183 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=182
2012-09-18T17:28:04.623Z,1347989284.624 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=94
2012-09-18T17:28:04.655Z,1347989284.655 [CommandLine](IMPORTANT): got command report touch DVL_micro.durationOfLastRun
2012-09-18T17:28:05.063Z,1347989285.063 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:28:05.203Z,1347989285.203 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72
2012-09-18T17:28:05.259Z,1347989285.259 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92
2012-09-18T17:28:05.307Z,1347989285.307 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-18T17:28:05.359Z,1347989285.359 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-18T17:28:05.683Z,1347989285.683 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=280
2012-09-18T17:28:05.759Z,1347989285.759 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=93
2012-09-18T17:28:05.763Z,1347989285.763 [Reporter](INFO): DVL_micro.RSSIPoints 310 count
2012-09-18T17:28:05.763Z,1347989285.763 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:05.764Z,1347989285.764 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:05.765Z,1347989285.765 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063600 s
2012-09-18T17:28:05.859Z,1347989285.859 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=65
2012-09-18T17:28:05.979Z,1347989285.979 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-18T17:28:06.396Z,1347989286.396 [Reporter](INFO): DVL_micro.RSSIPoints 590 count
2012-09-18T17:28:06.397Z,1347989286.397 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063500 s
2012-09-18T17:28:06.491Z,1347989286.491 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:06.514Z,1347989286.514 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:06.979Z,1347989286.979 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:28:07.162Z,1347989287.162 [Reporter](INFO): DVL_micro.durationOfLastRun 0.047800 s
2012-09-18T17:28:07.431Z,1347989287.431 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=76
2012-09-18T17:28:07.884Z,1347989287.884 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=116
2012-09-18T17:28:08.323Z,1347989288.323 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:28:08.477Z,1347989288.477 [Reporter](INFO): DVL_micro.RSSIPoints 2556 count
2012-09-18T17:28:08.477Z,1347989288.477 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:08.483Z,1347989288.483 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:08.484Z,1347989288.484 [Reporter](INFO): DVL_micro.durationOfLastRun 1.869600 s
2012-09-18T17:28:08.692Z,1347989288.691 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=120
2012-09-18T17:28:08.827Z,1347989288.827 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-18T17:28:08.984Z,1347989288.984 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=128
2012-09-18T17:28:09.115Z,1347989289.115 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-18T17:28:09.285Z,1347989289.285 [Reporter](INFO): DVL_micro.RSSIPoints 830 count
2012-09-18T17:28:09.286Z,1347989289.286 [Reporter](INFO): DVL_micro.durationOfLastRun 0.068400 s
2012-09-18T17:28:09.655Z,1347989289.655 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:09.696Z,1347989289.696 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:09.910Z,1347989289.910 [Reporter](INFO): DVL_micro.durationOfLastRun 0.494600 s
2012-09-18T17:28:10.151Z,1347989290.151 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=25
2012-09-18T17:28:10.603Z,1347989290.603 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=97
2012-09-18T17:28:10.747Z,1347989290.747 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:10.747Z,1347989290.747 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:10.748Z,1347989290.748 [Reporter](INFO): DVL_micro.durationOfLastRun 0.332900 s
2012-09-18T17:28:11.051Z,1347989291.051 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=105
2012-09-18T17:28:11.491Z,1347989291.491 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:28:11.843Z,1347989291.843 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=92
2012-09-18T17:28:11.975Z,1347989291.975 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=260
2012-09-18T17:28:12.095Z,1347989292.095 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=300
2012-09-18T17:28:12.196Z,1347989292.195 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-18T17:28:12.287Z,1347989292.287 [Reporter](INFO): DVL_micro.RSSIPoints 668 count
2012-09-18T17:28:12.288Z,1347989292.288 [Reporter](INFO): DVL_micro.durationOfLastRun 0.574100 s
2012-09-18T17:28:12.550Z,1347989292.550 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:12.588Z,1347989292.588 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:12.842Z,1347989292.842 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=62
2012-09-18T17:28:12.874Z,1347989292.874 [Reporter](INFO): DVL_micro.durationOfLastRun 0.177000 s
2012-09-18T17:28:13.103Z,1347989293.103 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=390
2012-09-18T17:28:13.307Z,1347989293.307 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=14
2012-09-18T17:28:13.551Z,1347989293.551 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-18T17:28:14.007Z,1347989294.007 [Reporter](INFO): DVL_micro.RSSIPoints 1356 count
2012-09-18T17:28:14.008Z,1347989294.008 [Reporter](INFO): DVL_micro.durationOfLastRun 0.064500 s
2012-09-18T17:28:14.079Z,1347989294.079 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:14.119Z,1347989294.119 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:14.584Z,1347989294.583 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=212
2012-09-18T17:28:14.714Z,1347989294.714 [Reporter](INFO): DVL_micro.durationOfLastRun 0.185700 s
2012-09-18T17:28:15.036Z,1347989295.036 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=252
2012-09-18T17:28:15.472Z,1347989295.472 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=132
2012-09-18T17:28:15.911Z,1347989295.911 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:28:16.146Z,1347989296.146 [Reporter](INFO): DVL_micro.RSSIPoints 2556 count
2012-09-18T17:28:16.150Z,1347989296.150 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062600 s
2012-09-18T17:28:16.851Z,1347989296.851 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:16.880Z,1347989296.880 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:17.081Z,1347989297.081 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:17.081Z,1347989297.081 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:17.088Z,1347989297.088 [Reporter](INFO): DVL_micro.durationOfLastRun 0.830500 s
2012-09-18T17:28:17.743Z,1347989297.743 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=74
2012-09-18T17:28:17.954Z,1347989297.954 [Reporter](INFO): DVL_micro.durationOfLastRun 0.277200 s
2012-09-18T17:28:18.584Z,1347989298.584 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=82
2012-09-18T17:28:19.427Z,1347989299.427 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=155
2012-09-18T17:28:20.272Z,1347989300.272 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:28:20.763Z,1347989300.763 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:20.764Z,1347989300.764 [Reporter](INFO): DVL_micro.durationOfLastRun 0.072900 s
2012-09-18T17:28:21.263Z,1347989301.263 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:21.295Z,1347989301.295 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:21.861Z,1347989301.861 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:21.862Z,1347989301.862 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:21.867Z,1347989301.867 [Reporter](INFO): DVL_micro.durationOfLastRun 0.165700 s
2012-09-18T17:28:22.159Z,1347989302.159 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=123
2012-09-18T17:28:22.987Z,1347989302.987 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=35
2012-09-18T17:28:23.823Z,1347989303.823 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=11
2012-09-18T17:28:24.664Z,1347989304.664 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:28:25.204Z,1347989305.204 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:25.205Z,1347989305.204 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063200 s
2012-09-18T17:28:25.671Z,1347989305.671 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:25.734Z,1347989305.734 [DVL_micro](ERROR): DVL NQ1 uart error: serial timeout
2012-09-18T17:28:25.735Z,1347989305.735 [DVL_micro] Communications Fault, FailCount= 1
2012-09-18T17:28:25.735Z,1347989305.735 [DVL_micro](ERROR): Communications Fault
2012-09-18T17:28:25.737Z,1347989305.737 [DVL_micro](INFO): uninitialize:Powering down
2012-09-18T17:28:26.254Z,1347989306.254 [CBIT](ERROR): Communications Fault in component: DVL_micro
2012-09-18T17:28:26.254Z,1347989306.254 [CBIT](INFO): Clearing failed state for component DVL_micro
2012-09-18T17:28:26.259Z,1347989306.259 [DVL_micro] No Fault, FailCount= 1
2012-09-18T17:28:26.260Z,1347989306.260 [Reporter](INFO): DVL_micro.component_avgCurrent no_value
2012-09-18T17:28:26.261Z,1347989306.261 [Reporter](INFO): DVL_micro.component_current no_value
2012-09-18T17:28:26.261Z,1347989306.261 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:28:26.280Z,1347989306.280 [DVL_micro](INFO): Initializing
2012-09-18T17:28:26.280Z,1347989306.280 [DVL_micro](INFO): start:Powering up
2012-09-18T17:28:26.800Z,1347989306.800 [Reporter](INFO): DVL_micro.component_avgCurrent no_value
2012-09-18T17:28:26.801Z,1347989306.801 [Reporter](INFO): DVL_micro.component_current no_value
2012-09-18T17:28:27.786Z,1347989307.786 [DVL_micro](INFO): Querying output modes
2012-09-18T17:28:27.787Z,1347989307.787 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606
2012-09-18T17:28:27.791Z,1347989307.791 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-09-18T17:28:27.791Z,1347989307.791 [DVL_micro](INFO): NQ1 output enabled
2012-09-18T17:28:27.791Z,1347989307.791 [DVL_micro](INFO): RSSI output enabled
2012-09-18T17:28:27.791Z,1347989307.791 [DVL_micro](INFO): ADCP output enabled
2012-09-18T17:28:27.792Z,1347989307.792 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525
2012-09-18T17:28:27.801Z,1347989307.801 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON
2012-09-18T17:28:27.939Z,1347989307.939 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72
2012-09-18T17:28:28.011Z,1347989308.011 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=252
2012-09-18T17:28:28.083Z,1347989308.083 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=464
2012-09-18T17:28:28.096Z,1347989308.096 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-18T17:28:28.130Z,1347989308.130 [Reporter](INFO): DVL_micro.RSSIPoints 310 count
2012-09-18T17:28:28.131Z,1347989308.131 [Reporter](INFO): DVL_micro.durationOfLastRun 0.243600 s
2012-09-18T17:28:28.391Z,1347989308.391 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120
2012-09-18T17:28:28.501Z,1347989308.501 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=188
2012-09-18T17:28:28.587Z,1347989308.588 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-18T17:28:28.691Z,1347989308.691 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-18T17:28:28.835Z,1347989308.835 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104
2012-09-18T17:28:28.883Z,1347989308.884 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-18T17:28:28.939Z,1347989308.939 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-09-18T17:28:28.980Z,1347989308.980 [Reporter](INFO): DVL_micro.RSSIPoints 590 count
2012-09-18T17:28:28.981Z,1347989308.981 [Reporter](INFO): DVL_micro.durationOfLastRun 0.540600 s
2012-09-18T17:28:29.007Z,1347989309.007 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-18T17:28:29.287Z,1347989309.287 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120
2012-09-18T17:28:29.383Z,1347989309.383 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-18T17:28:29.504Z,1347989309.504 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=224
2012-09-18T17:28:29.596Z,1347989309.596 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-18T17:28:29.687Z,1347989309.687 [Reporter](INFO): DVL_micro.RSSIPoints 590 count
2012-09-18T17:28:29.688Z,1347989309.688 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:29.689Z,1347989309.689 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:29.689Z,1347989309.690 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061500 s
2012-09-18T17:28:29.955Z,1347989309.955 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=88
2012-09-18T17:28:30.096Z,1347989310.096 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-18T17:28:30.255Z,1347989310.255 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=192
2012-09-18T17:28:30.388Z,1347989310.388 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-18T17:28:30.442Z,1347989310.442 [Reporter](INFO): DVL_micro.RSSIPoints 830 count
2012-09-18T17:28:30.443Z,1347989310.443 [Reporter](INFO): DVL_micro.durationOfLastRun 0.742000 s
2012-09-18T17:28:30.759Z,1347989310.759 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=316
2012-09-18T17:28:30.847Z,1347989310.847 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-18T17:28:30.968Z,1347989310.968 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44
2012-09-18T17:28:31.091Z,1347989311.092 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-18T17:28:31.135Z,1347989311.135 [Reporter](INFO): DVL_micro.RSSIPoints 668 count
2012-09-18T17:28:31.136Z,1347989311.136 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:31.137Z,1347989311.137 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:31.137Z,1347989311.137 [Reporter](INFO): DVL_micro.durationOfLastRun 0.590600 s
2012-09-18T17:28:31.688Z,1347989311.688 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-09-18T17:28:31.810Z,1347989311.810 [Reporter](INFO): DVL_micro.durationOfLastRun 0.064000 s
2012-09-18T17:28:31.933Z,1347989311.934 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=132
2012-09-18T17:28:32.159Z,1347989312.159 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-09-18T17:28:32.396Z,1347989312.396 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-18T17:28:32.504Z,1347989312.504 [Reporter](INFO): DVL_micro.RSSIPoints 1356 count
2012-09-18T17:28:32.504Z,1347989312.504 [Reporter](INFO): DVL_micro.durationOfLastRun 1.246300 s
2012-09-18T17:28:32.965Z,1347989312.965 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063600 s
2012-09-18T17:28:33.451Z,1347989313.451 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=92
2012-09-18T17:28:33.931Z,1347989313.931 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=165
2012-09-18T17:28:34.401Z,1347989314.401 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=78
2012-09-18T17:28:34.872Z,1347989314.872 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-18T17:28:34.969Z,1347989314.969 [Reporter](INFO): DVL_micro.RSSIPoints 2716 count
2012-09-18T17:28:34.971Z,1347989314.971 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:34.972Z,1347989314.972 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:34.973Z,1347989314.973 [Reporter](INFO): DVL_micro.durationOfLastRun 2.430100 s
2012-09-18T17:28:35.554Z,1347989315.554 [Reporter](INFO): DVL_micro.durationOfLastRun 0.069000 s
2012-09-18T17:28:35.871Z,1347989315.871 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:35.911Z,1347989315.911 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:36.026Z,1347989316.026 [Reporter](INFO): DVL_micro.durationOfLastRun 0.885700 s
2012-09-18T17:28:36.770Z,1347989316.770 [Reporter](INFO): DVL_micro.durationOfLastRun 0.214200 s
2012-09-18T17:28:36.780Z,1347989316.780 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=201
2012-09-18T17:28:37.619Z,1347989317.619 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=241
2012-09-18T17:28:38.439Z,1347989318.439 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=25
2012-09-18T17:28:39.284Z,1347989319.284 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:28:39.492Z,1347989319.492 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:39.493Z,1347989319.493 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:39.494Z,1347989319.494 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:39.500Z,1347989319.500 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062300 s
2012-09-18T17:28:40.004Z,1347989320.003 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062300 s
2012-09-18T17:28:40.247Z,1347989320.247 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:40.277Z,1347989320.277 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:40.523Z,1347989320.523 [Reporter](INFO): DVL_micro.durationOfLastRun 0.776000 s
2012-09-18T17:28:41.143Z,1347989321.143 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=105
2012-09-18T17:28:41.269Z,1347989321.269 [Reporter](INFO): DVL_micro.durationOfLastRun 0.337000 s
2012-09-18T17:28:41.984Z,1347989321.984 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=147
2012-09-18T17:28:42.811Z,1347989322.811 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=27
2012-09-18T17:28:43.647Z,1347989323.647 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:28:44.007Z,1347989324.007 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:44.008Z,1347989324.008 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:44.009Z,1347989324.009 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:44.009Z,1347989324.009 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061500 s
2012-09-18T17:28:44.662Z,1347989324.662 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:44.705Z,1347989324.705 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:45.109Z,1347989325.109 [Reporter](INFO): DVL_micro.durationOfLastRun 0.335200 s
2012-09-18T17:28:45.547Z,1347989325.547 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=75
2012-09-18T17:28:46.387Z,1347989326.387 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=115
2012-09-18T17:28:47.223Z,1347989327.223 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=91
2012-09-18T17:28:48.059Z,1347989328.059 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:28:48.444Z,1347989328.444 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:48.445Z,1347989328.445 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:48.445Z,1347989328.445 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:48.447Z,1347989328.447 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062900 s
2012-09-18T17:28:49.067Z,1347989329.067 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:49.105Z,1347989329.105 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:49.604Z,1347989329.604 [Reporter](INFO): DVL_micro.durationOfLastRun 0.316300 s
2012-09-18T17:28:49.972Z,1347989329.972 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=216
2012-09-18T17:28:50.799Z,1347989330.799 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=128
2012-09-18T17:28:51.632Z,1347989331.632 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=40
2012-09-18T17:28:52.468Z,1347989332.468 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:28:52.930Z,1347989332.930 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:52.931Z,1347989332.931 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:52.932Z,1347989332.932 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:52.933Z,1347989332.932 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063600 s
2012-09-18T17:28:53.271Z,1347989333.271 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:53.302Z,1347989333.302 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:53.381Z,1347989333.381 [Reporter](INFO): DVL_micro.durationOfLastRun 0.620900 s
2012-09-18T17:28:54.108Z,1347989334.108 [Reporter](INFO): DVL_micro.durationOfLastRun 0.113500 s
2012-09-18T17:28:54.164Z,1347989334.164 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=72
2012-09-18T17:28:54.999Z,1347989334.999 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=49
2012-09-18T17:28:55.839Z,1347989335.839 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=90
2012-09-18T17:28:56.679Z,1347989336.679 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:28:56.970Z,1347989336.970 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:28:56.976Z,1347989336.976 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:28:56.977Z,1347989336.977 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:28:56.978Z,1347989336.978 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063400 s
2012-09-18T17:28:57.415Z,1347989337.415 [CTD_NeilBrown](ERROR): Bad response: 9.72052
2012-09-18T17:28:57.615Z,1347989337.615 [DVL_micro](DEBUG): NQ1
2012-09-18T17:28:57.655Z,1347989337.655 [DVL_micro](DEBUG): ADCP
2012-09-18T17:28:58.181Z,1347989338.181 [Reporter](INFO): DVL_micro.durationOfLastRun 0.203800 s
2012-09-18T17:28:58.519Z,1347989338.519 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=259
2012-09-18T17:28:59.343Z,1347989339.343 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=76
2012-09-18T17:29:00.184Z,1347989340.184 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=116
2012-09-18T17:29:01.025Z,1347989341.025 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:01.301Z,1347989341.301 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:01.302Z,1347989341.302 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:01.303Z,1347989341.303 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:01.304Z,1347989341.304 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063100 s
2012-09-18T17:29:02.014Z,1347989342.014 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:02.044Z,1347989342.044 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:02.352Z,1347989342.352 [Reporter](INFO): DVL_micro.durationOfLastRun 0.120500 s
2012-09-18T17:29:02.899Z,1347989342.899 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=0
2012-09-18T17:29:03.743Z,1347989343.743 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=72
2012-09-18T17:29:04.584Z,1347989344.584 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=113
2012-09-18T17:29:05.419Z,1347989345.419 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:05.672Z,1347989345.672 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:05.673Z,1347989345.673 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:05.674Z,1347989345.674 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:05.676Z,1347989345.676 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062900 s
2012-09-18T17:29:06.379Z,1347989346.379 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:06.405Z,1347989346.405 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:06.668Z,1347989346.668 [Reporter](INFO): DVL_micro.durationOfLastRun 0.133900 s
2012-09-18T17:29:07.268Z,1347989347.267 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=21
2012-09-18T17:29:08.112Z,1347989348.112 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=93
2012-09-18T17:29:08.939Z,1347989348.939 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=5
2012-09-18T17:29:09.787Z,1347989349.787 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:10.169Z,1347989350.169 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:10.171Z,1347989350.171 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:10.172Z,1347989350.172 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:10.173Z,1347989350.173 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062800 s
2012-09-18T17:29:10.611Z,1347989350.611 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:10.638Z,1347989350.638 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:10.785Z,1347989350.785 [Reporter](INFO): DVL_micro.durationOfLastRun 0.027600 s
2012-09-18T17:29:11.499Z,1347989351.499 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=15
2012-09-18T17:29:12.347Z,1347989352.348 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=119
2012-09-18T17:29:13.197Z,1347989353.197 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=256
2012-09-18T17:29:14.013Z,1347989354.013 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:14.185Z,1347989354.185 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:14.186Z,1347989354.186 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:14.192Z,1347989354.192 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:14.193Z,1347989354.193 [Reporter](INFO): DVL_micro.durationOfLastRun 3.490500 s
2012-09-18T17:29:14.735Z,1347989354.735 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063900 s
2012-09-18T17:29:15.015Z,1347989355.015 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:15.038Z,1347989355.038 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:15.223Z,1347989355.223 [Reporter](INFO): DVL_micro.durationOfLastRun 0.109500 s
2012-09-18T17:29:15.903Z,1347989355.903 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=33
2012-09-18T17:29:16.747Z,1347989356.747 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=105
2012-09-18T17:29:17.575Z,1347989357.575 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=19
2012-09-18T17:29:18.419Z,1347989358.419 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:18.740Z,1347989358.740 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:18.740Z,1347989358.740 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:18.741Z,1347989358.741 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:18.743Z,1347989358.743 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061600 s
2012-09-18T17:29:19.287Z,1347989359.287 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:19.311Z,1347989359.311 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:19.000Z,1347989360.000 [Reporter](INFO): DVL_micro.durationOfLastRun 0.027400 s
2012-09-18T17:29:20.180Z,1347989360.180 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=92
2012-09-18T17:29:21.019Z,1347989361.019 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=100
2012-09-18T17:29:21.867Z,1347989361.867 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=76
2012-09-18T17:29:22.711Z,1347989362.711 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:23.396Z,1347989363.396 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:23.397Z,1347989363.397 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:23.398Z,1347989363.398 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:23.399Z,1347989363.399 [Reporter](INFO): DVL_micro.durationOfLastRun 0.069700 s
2012-09-18T17:29:23.627Z,1347989363.626 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:23.673Z,1347989363.673 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:23.965Z,1347989363.965 [Reporter](INFO): DVL_micro.durationOfLastRun 0.248800 s
2012-09-18T17:29:24.543Z,1347989364.543 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=376
2012-09-18T17:29:25.349Z,1347989365.349 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=0
2012-09-18T17:29:26.188Z,1347989366.188 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=8
2012-09-18T17:29:27.035Z,1347989367.035 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:27.388Z,1347989367.388 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:27.389Z,1347989367.389 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:27.391Z,1347989367.391 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:27.392Z,1347989367.392 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062500 s
2012-09-18T17:29:28.026Z,1347989368.026 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:28.054Z,1347989368.054 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:28.491Z,1347989368.491 [DVL_micro](DEBUG): RSSI read bytes: 4052 of 9592, #buffered=0
2012-09-18T17:29:28.572Z,1347989368.572 [Reporter](INFO): DVL_micro.durationOfLastRun 0.024100 s
2012-09-18T17:29:28.923Z,1347989368.923 [DVL_micro](DEBUG): RSSI read bytes: 5540 of 5540, #buffered=93
2012-09-18T17:29:29.760Z,1347989369.760 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=69
2012-09-18T17:29:30.599Z,1347989370.599 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=110
2012-09-18T17:29:31.447Z,1347989371.447 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:31.000Z,1347989372.000 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:32.001Z,1347989372.001 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:32.001Z,1347989372.001 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:32.002Z,1347989372.002 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062400 s
2012-09-18T17:29:32.306Z,1347989372.306 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:32.345Z,1347989372.345 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:32.443Z,1347989372.443 [Reporter](INFO): DVL_micro.durationOfLastRun 0.672200 s
2012-09-18T17:29:33.211Z,1347989373.211 [Reporter](INFO): DVL_micro.durationOfLastRun 0.157700 s
2012-09-18T17:29:33.215Z,1347989373.215 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=262
2012-09-18T17:29:34.039Z,1347989374.039 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=110
2012-09-18T17:29:34.880Z,1347989374.880 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=150
2012-09-18T17:29:35.707Z,1347989375.707 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:35.898Z,1347989375.898 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:35.902Z,1347989375.902 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:35.908Z,1347989375.908 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:35.909Z,1347989375.908 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062900 s
2012-09-18T17:29:36.477Z,1347989376.477 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062900 s
2012-09-18T17:29:36.683Z,1347989376.683 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:36.718Z,1347989376.718 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:37.018Z,1347989377.018 [Reporter](INFO): DVL_micro.durationOfLastRun 0.087500 s
2012-09-18T17:29:37.603Z,1347989377.603 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=390
2012-09-18T17:29:38.407Z,1347989378.407 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=14
2012-09-18T17:29:39.280Z,1347989379.280 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=407
2012-09-18T17:29:40.093Z,1347989380.093 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:40.669Z,1347989380.668 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:40.669Z,1347989380.669 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:40.671Z,1347989380.671 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:40.673Z,1347989380.672 [Reporter](INFO): DVL_micro.durationOfLastRun 0.072600 s
2012-09-18T17:29:41.026Z,1347989381.026 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:41.049Z,1347989381.049 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:41.235Z,1347989381.235 [Reporter](INFO): DVL_micro.durationOfLastRun 0.027600 s
2012-09-18T17:29:41.911Z,1347989381.911 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=41
2012-09-18T17:29:42.780Z,1347989382.780 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=404
2012-09-18T17:29:43.593Z,1347989383.593 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=124
2012-09-18T17:29:44.423Z,1347989384.423 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:44.697Z,1347989384.697 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:44.697Z,1347989384.697 [Reporter](INFO): DVL_micro.durationOfLastRun 0.075500 s
2012-09-18T17:29:45.343Z,1347989385.342 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:45.384Z,1347989385.384 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:45.732Z,1347989385.732 [Reporter](INFO): DVL_micro.durationOfLastRun 0.217500 s
2012-09-18T17:29:46.227Z,1347989386.227 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=11
2012-09-18T17:29:47.080Z,1347989387.080 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=180
2012-09-18T17:29:47.911Z,1347989387.911 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=93
2012-09-18T17:29:48.755Z,1347989388.755 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:49.284Z,1347989389.284 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:49.285Z,1347989389.285 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:49.286Z,1347989389.286 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:49.287Z,1347989389.287 [Reporter](INFO): DVL_micro.durationOfLastRun 0.064000 s
2012-09-18T17:29:49.669Z,1347989389.670 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:49.712Z,1347989389.712 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:49.840Z,1347989389.840 [Reporter](INFO): DVL_micro.durationOfLastRun 0.751700 s
2012-09-18T17:29:50.569Z,1347989390.569 [Reporter](INFO): DVL_micro.durationOfLastRun 0.325100 s
2012-09-18T17:29:50.572Z,1347989390.572 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=195
2012-09-18T17:29:51.399Z,1347989391.399 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=107
2012-09-18T17:29:52.255Z,1347989392.255 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=309
2012-09-18T17:29:53.071Z,1347989393.071 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:53.288Z,1347989393.288 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:53.289Z,1347989393.289 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:53.305Z,1347989393.305 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:53.306Z,1347989393.306 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061800 s
2012-09-18T17:29:53.963Z,1347989393.963 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:54.003Z,1347989394.003 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:54.472Z,1347989394.472 [Reporter](INFO): DVL_micro.durationOfLastRun 0.114000 s
2012-09-18T17:29:54.847Z,1347989394.847 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=83
2012-09-18T17:29:55.693Z,1347989395.693 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=156
2012-09-18T17:29:56.523Z,1347989396.523 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=68
2012-09-18T17:29:57.380Z,1347989397.380 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:29:57.869Z,1347989397.869 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:29:57.870Z,1347989397.870 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:29:57.876Z,1347989397.876 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:29:57.876Z,1347989397.876 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061200 s
2012-09-18T17:29:58.155Z,1347989398.155 [DVL_micro](DEBUG): NQ1
2012-09-18T17:29:58.188Z,1347989398.188 [DVL_micro](DEBUG): ADCP
2012-09-18T17:29:58.423Z,1347989398.423 [Reporter](INFO): DVL_micro.durationOfLastRun 0.133100 s
2012-09-18T17:29:59.055Z,1347989399.055 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=256
2012-09-18T17:29:59.874Z,1347989399.874 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=9
2012-09-18T17:30:00.730Z,1347989400.730 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=211
2012-09-18T17:30:01.551Z,1347989401.551 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:01.776Z,1347989401.776 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:01.777Z,1347989401.777 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:01.787Z,1347989401.787 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:01.788Z,1347989401.788 [Reporter](INFO): DVL_micro.durationOfLastRun 0.069400 s
2012-09-18T17:30:02.079Z,1347989402.079 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:02.118Z,1347989402.118 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:02.465Z,1347989402.465 [Reporter](INFO): DVL_micro.durationOfLastRun 0.262400 s
2012-09-18T17:30:02.580Z,1347989402.580 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=100
2012-09-18T17:30:03.043Z,1347989403.043 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=204
2012-09-18T17:30:03.493Z,1347989403.493 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=341
2012-09-18T17:30:03.914Z,1347989403.914 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:30:04.035Z,1347989404.035 [Reporter](INFO): DVL_micro.RSSIPoints 2556 count
2012-09-18T17:30:04.035Z,1347989404.035 [Reporter](INFO): DVL_micro.durationOfLastRun 1.626600 s
2012-09-18T17:30:04.047Z,1347989404.047 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-18T17:30:04.107Z,1347989404.107 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92
2012-09-18T17:30:04.171Z,1347989404.171 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=208
2012-09-18T17:30:04.231Z,1347989404.231 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-18T17:30:04.503Z,1347989404.503 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120
2012-09-18T17:30:04.602Z,1347989404.602 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-18T17:30:04.700Z,1347989404.700 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-18T17:30:04.811Z,1347989404.811 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-18T17:30:04.850Z,1347989404.850 [Reporter](INFO): DVL_micro.RSSIPoints 590 count
2012-09-18T17:30:04.854Z,1347989404.854 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:04.855Z,1347989404.855 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:04.856Z,1347989404.856 [Reporter](INFO): DVL_micro.durationOfLastRun 0.524700 s
2012-09-18T17:30:05.331Z,1347989405.331 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:05.358Z,1347989405.358 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:05.451Z,1347989405.451 [Reporter](INFO): DVL_micro.durationOfLastRun 0.083700 s
2012-09-18T17:30:05.819Z,1347989405.819 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=28
2012-09-18T17:30:06.267Z,1347989406.267 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=36
2012-09-18T17:30:06.727Z,1347989406.727 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=204
2012-09-18T17:30:07.168Z,1347989407.168 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:30:07.303Z,1347989407.303 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=104
2012-09-18T17:30:07.351Z,1347989407.351 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-18T17:30:07.412Z,1347989407.411 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=112
2012-09-18T17:30:07.487Z,1347989407.487 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-18T17:30:07.580Z,1347989407.580 [Reporter](INFO): DVL_micro.RSSIPoints 310 count
2012-09-18T17:30:07.581Z,1347989407.581 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061800 s
2012-09-18T17:30:07.755Z,1347989407.755 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=120
2012-09-18T17:30:07.855Z,1347989407.855 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=92
2012-09-18T17:30:07.972Z,1347989407.972 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=256
2012-09-18T17:30:08.064Z,1347989408.064 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-18T17:30:08.261Z,1347989408.260 [Reporter](INFO): DVL_micro.RSSIPoints 590 count
2012-09-18T17:30:08.261Z,1347989408.261 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:08.264Z,1347989408.264 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:08.265Z,1347989408.265 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063200 s
2012-09-18T17:30:08.587Z,1347989408.587 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:08.611Z,1347989408.611 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:08.872Z,1347989408.872 [Reporter](INFO): DVL_micro.durationOfLastRun 0.026300 s
2012-09-18T17:30:09.103Z,1347989409.103 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=267
2012-09-18T17:30:09.538Z,1347989409.538 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=115
2012-09-18T17:30:09.979Z,1347989409.979 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=92
2012-09-18T17:30:10.419Z,1347989410.419 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:30:10.793Z,1347989410.793 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=121
2012-09-18T17:30:10.927Z,1347989410.927 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=30
2012-09-18T17:30:10.935Z,1347989410.935 [Reporter](INFO): DVL_micro.RSSIPoints 2556 count
2012-09-18T17:30:10.936Z,1347989410.935 [Reporter](INFO): DVL_micro.durationOfLastRun 0.064000 s
2012-09-18T17:30:11.072Z,1347989411.072 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=2
2012-09-18T17:30:11.223Z,1347989411.223 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-18T17:30:11.596Z,1347989411.596 [Reporter](INFO): DVL_micro.RSSIPoints 830 count
2012-09-18T17:30:11.597Z,1347989411.597 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:11.598Z,1347989411.598 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:11.603Z,1347989411.603 [Reporter](INFO): DVL_micro.durationOfLastRun 0.069000 s
2012-09-18T17:30:11.747Z,1347989411.747 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:11.793Z,1347989411.793 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:12.220Z,1347989412.220 [Reporter](INFO): DVL_micro.durationOfLastRun 0.232100 s
2012-09-18T17:30:12.241Z,1347989412.241 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=7
2012-09-18T17:30:12.695Z,1347989412.695 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=111
2012-09-18T17:30:13.135Z,1347989413.135 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=55
2012-09-18T17:30:13.587Z,1347989413.587 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:30:13.710Z,1347989413.710 [Reporter](INFO): DVL_micro.RSSIPoints 2556 count
2012-09-18T17:30:13.715Z,1347989413.715 [Reporter](INFO): DVL_micro.durationOfLastRun 1.658400 s
2012-09-18T17:30:13.931Z,1347989413.931 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60
2012-09-18T17:30:14.043Z,1347989414.043 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36
2012-09-18T17:30:14.172Z,1347989414.172 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=140
2012-09-18T17:30:14.275Z,1347989414.276 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-18T17:30:14.398Z,1347989414.398 [Reporter](INFO): DVL_micro.RSSIPoints 668 count
2012-09-18T17:30:14.402Z,1347989414.402 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:14.407Z,1347989414.407 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:14.408Z,1347989414.408 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062700 s
2012-09-18T17:30:14.646Z,1347989414.646 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:14.705Z,1347989414.705 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:15.036Z,1347989415.036 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=1247
2012-09-18T17:30:15.043Z,1347989415.043 [Reporter](INFO): DVL_micro.durationOfLastRun 0.323000 s
2012-09-18T17:30:15.168Z,1347989415.168 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=39
2012-09-18T17:30:15.412Z,1347989415.411 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=111
2012-09-18T17:30:15.651Z,1347989415.651 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-18T17:30:15.755Z,1347989415.755 [Reporter](INFO): DVL_micro.RSSIPoints 1356 count
2012-09-18T17:30:15.756Z,1347989415.756 [Reporter](INFO): DVL_micro.durationOfLastRun 0.671700 s
2012-09-18T17:30:16.179Z,1347989416.179 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:16.216Z,1347989416.216 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:16.232Z,1347989416.232 [Reporter](INFO): DVL_micro.durationOfLastRun 0.452300 s
2012-09-18T17:30:16.679Z,1347989416.679 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=93
2012-09-18T17:30:16.935Z,1347989416.935 [Reporter](INFO): DVL_micro.durationOfLastRun 0.165100 s
2012-09-18T17:30:17.119Z,1347989417.119 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=5
2012-09-18T17:30:17.593Z,1347989417.593 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=173
2012-09-18T17:30:18.015Z,1347989418.015 [DVL_micro](DEBUG): RSSI read bytes: 5112 of 5112, #buffered=4
2012-09-18T17:30:18.231Z,1347989418.231 [Reporter](INFO): DVL_micro.RSSIPoints 2556 count
2012-09-18T17:30:18.232Z,1347989418.232 [Reporter](INFO): DVL_micro.durationOfLastRun 0.069300 s
2012-09-18T17:30:18.951Z,1347989418.951 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:18.993Z,1347989418.993 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:19.084Z,1347989419.085 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:19.086Z,1347989419.086 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:19.095Z,1347989419.095 [Reporter](INFO): DVL_micro.durationOfLastRun 0.821300 s
2012-09-18T17:30:19.835Z,1347989419.835 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=30
2012-09-18T17:30:20.147Z,1347989420.147 [Reporter](INFO): DVL_micro.durationOfLastRun 0.287400 s
2012-09-18T17:30:20.707Z,1347989420.707 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=422
2012-09-18T17:30:21.515Z,1347989421.515 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=78
2012-09-18T17:30:22.347Z,1347989422.347 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:22.915Z,1347989422.915 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:22.916Z,1347989422.916 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062000 s
2012-09-18T17:30:23.227Z,1347989423.227 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:23.283Z,1347989423.283 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:23.427Z,1347989423.427 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:23.427Z,1347989423.427 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:23.428Z,1347989423.428 [Reporter](INFO): DVL_micro.durationOfLastRun 0.694000 s
2012-09-18T17:30:24.115Z,1347989424.115 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=59
2012-09-18T17:30:24.181Z,1347989424.181 [Reporter](INFO): DVL_micro.durationOfLastRun 0.331000 s
2012-09-18T17:30:24.950Z,1347989424.950 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=3
2012-09-18T17:30:25.795Z,1347989425.795 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=107
2012-09-18T17:30:26.626Z,1347989426.626 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:26.888Z,1347989426.888 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:26.889Z,1347989426.889 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061200 s
2012-09-18T17:30:27.530Z,1347989427.530 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:27.574Z,1347989427.574 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:27.972Z,1347989427.972 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:27.973Z,1347989427.973 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:27.979Z,1347989427.979 [Reporter](INFO): DVL_micro.durationOfLastRun 0.270400 s
2012-09-18T17:30:28.443Z,1347989428.443 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=260
2012-09-18T17:30:29.260Z,1347989429.260 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=12
2012-09-18T17:30:30.103Z,1347989430.103 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=84
2012-09-18T17:30:30.951Z,1347989430.951 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:31.315Z,1347989431.315 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:31.315Z,1347989431.315 [Reporter](INFO): DVL_micro.durationOfLastRun 0.068900 s
2012-09-18T17:30:31.863Z,1347989431.863 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:31.890Z,1347989431.890 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:32.347Z,1347989432.347 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:32.347Z,1347989432.347 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:32.348Z,1347989432.348 [Reporter](INFO): DVL_micro.durationOfLastRun 0.127500 s
2012-09-18T17:30:32.747Z,1347989432.747 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=32
2012-09-18T17:30:33.588Z,1347989433.588 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=73
2012-09-18T17:30:34.431Z,1347989434.431 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=145
2012-09-18T17:30:35.264Z,1347989435.263 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:35.680Z,1347989435.680 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:35.681Z,1347989435.681 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062300 s
2012-09-18T17:30:36.187Z,1347989436.187 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:36.212Z,1347989436.212 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:36.848Z,1347989436.848 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:36.849Z,1347989436.849 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:36.849Z,1347989436.849 [Reporter](INFO): DVL_micro.durationOfLastRun 0.058000 s
2012-09-18T17:30:37.082Z,1347989437.082 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=49
2012-09-18T17:30:37.922Z,1347989437.922 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=90
2012-09-18T17:30:38.760Z,1347989438.760 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=131
2012-09-18T17:30:39.588Z,1347989439.588 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:40.471Z,1347989440.471 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:40.515Z,1347989440.515 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:41.360Z,1347989441.360 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=74
2012-09-18T17:30:42.193Z,1347989442.193 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18
2012-09-18T17:30:43.039Z,1347989443.039 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=154
2012-09-18T17:30:43.871Z,1347989443.871 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:44.807Z,1347989444.807 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:44.831Z,1347989444.832 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:45.368Z,1347989445.368 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:45.370Z,1347989445.370 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:45.379Z,1347989445.379 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:45.380Z,1347989445.380 [Reporter](INFO): DVL_micro.durationOfLastRun 0.041400 s
2012-09-18T17:30:45.699Z,1347989445.699 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=121
2012-09-18T17:30:46.543Z,1347989446.543 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=194
2012-09-18T17:30:47.368Z,1347989447.368 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=42
2012-09-18T17:30:48.217Z,1347989448.217 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:48.715Z,1347989448.715 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:48.715Z,1347989448.715 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062500 s
2012-09-18T17:30:48.983Z,1347989448.983 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:49.013Z,1347989449.013 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:49.253Z,1347989449.253 [Reporter](INFO): DVL_micro.durationOfLastRun 0.164400 s
2012-09-18T17:30:49.864Z,1347989449.864 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=1
2012-09-18T17:30:50.707Z,1347989450.707 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=73
2012-09-18T17:30:51.543Z,1347989451.543 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=49
2012-09-18T17:30:52.399Z,1347989452.399 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:53.087Z,1347989453.087 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:53.089Z,1347989453.089 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062500 s
2012-09-18T17:30:53.335Z,1347989453.335 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:53.367Z,1347989453.367 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:53.619Z,1347989453.619 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:53.620Z,1347989453.620 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:53.620Z,1347989453.620 [Reporter](INFO): DVL_micro.durationOfLastRun 0.121700 s
2012-09-18T17:30:54.215Z,1347989454.215 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=5
2012-09-18T17:30:55.056Z,1347989455.056 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=14
2012-09-18T17:30:55.892Z,1347989455.892 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=23
2012-09-18T17:30:56.727Z,1347989456.727 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:30:57.203Z,1347989457.203 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:30:57.204Z,1347989457.204 [Reporter](INFO): DVL_micro.durationOfLastRun 0.072800 s
2012-09-18T17:30:57.739Z,1347989457.739 [DVL_micro](DEBUG): NQ1
2012-09-18T17:30:57.803Z,1347989457.803 [DVL_micro](DEBUG): ADCP
2012-09-18T17:30:58.269Z,1347989458.269 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:30:58.270Z,1347989458.270 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:30:58.279Z,1347989458.279 [Reporter](INFO): DVL_micro.durationOfLastRun 0.375900 s
2012-09-18T17:30:58.647Z,1347989458.647 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=239
2012-09-18T17:30:59.475Z,1347989459.475 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=120
2012-09-18T17:31:00.329Z,1347989460.329 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=289
2012-09-18T17:31:01.142Z,1347989461.142 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:01.585Z,1347989461.585 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:31:01.586Z,1347989461.586 [Reporter](INFO): DVL_micro.durationOfLastRun 0.063600 s
2012-09-18T17:31:02.147Z,1347989462.147 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:02.176Z,1347989462.176 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:02.580Z,1347989462.580 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:31:02.581Z,1347989462.581 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:31:02.582Z,1347989462.582 [Reporter](INFO): DVL_micro.durationOfLastRun 0.249400 s
2012-09-18T17:31:03.036Z,1347989463.036 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=34
2012-09-18T17:31:03.883Z,1347989463.883 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=138
2012-09-18T17:31:04.711Z,1347989464.711 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=50
2012-09-18T17:31:05.551Z,1347989465.551 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:05.950Z,1347989465.950 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:31:05.955Z,1347989465.955 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061500 s
2012-09-18T17:31:06.459Z,1347989466.459 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:06.504Z,1347989466.504 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:07.156Z,1347989467.156 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:31:07.157Z,1347989467.157 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:31:07.158Z,1347989467.158 [Reporter](INFO): DVL_micro.durationOfLastRun 0.207700 s
2012-09-18T17:31:07.347Z,1347989467.348 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=67
2012-09-18T17:31:08.203Z,1347989468.203 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=268
2012-09-18T17:31:09.031Z,1347989469.031 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=148
2012-09-18T17:31:09.855Z,1347989469.855 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:10.468Z,1347989470.468 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:31:10.469Z,1347989470.469 [Reporter](INFO): DVL_micro.durationOfLastRun 0.064000 s
2012-09-18T17:31:10.663Z,1347989470.663 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:10.706Z,1347989470.706 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:10.965Z,1347989470.966 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:31:10.967Z,1347989470.967 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:31:10.968Z,1347989470.968 [Reporter](INFO): DVL_micro.durationOfLastRun 0.241600 s
2012-09-18T17:31:11.539Z,1347989471.539 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=10
2012-09-18T17:31:12.380Z,1347989472.380 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=19
2012-09-18T17:31:13.215Z,1347989473.215 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=28
2012-09-18T17:31:14.051Z,1347989474.051 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:14.622Z,1347989474.622 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:31:14.627Z,1347989474.627 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062400 s
2012-09-18T17:31:15.059Z,1347989475.059 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:15.095Z,1347989475.095 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:15.689Z,1347989475.689 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:31:15.699Z,1347989475.699 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:31:15.699Z,1347989475.699 [Reporter](INFO): DVL_micro.durationOfLastRun 0.159100 s
2012-09-18T17:31:15.955Z,1347989475.955 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=149
2012-09-18T17:31:16.799Z,1347989476.799 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=222
2012-09-18T17:31:17.635Z,1347989477.635 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=199
2012-09-18T17:31:18.455Z,1347989478.455 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:19.055Z,1347989479.055 [Reporter](INFO): DVL_micro.RSSIPoints 4796 count
2012-09-18T17:31:19.055Z,1347989479.055 [Reporter](INFO): DVL_micro.durationOfLastRun 0.061800 s
2012-09-18T17:31:19.465Z,1347989479.465 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:19.519Z,1347989479.519 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:20.148Z,1347989480.148 [Reporter](INFO): DVL_micro.component_avgCurrent 3754.410267 mA
2012-09-18T17:31:20.149Z,1347989480.149 [Reporter](INFO): DVL_micro.component_current 3754.410267 mA
2012-09-18T17:31:20.149Z,1347989480.149 [Reporter](INFO): DVL_micro.durationOfLastRun 0.436500 s
2012-09-18T17:31:20.360Z,1347989480.360 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=144
2012-09-18T17:31:21.188Z,1347989481.188 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=25
2012-09-18T17:31:22.025Z,1347989482.026 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=2
2012-09-18T17:31:22.375Z,1347989482.375 [CommandLine](IMPORTANT): got command report clear
2012-09-18T17:31:22.867Z,1347989482.867 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:23.788Z,1347989483.788 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:23.886Z,1347989483.886 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:24.667Z,1347989484.667 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=37
2012-09-18T17:31:25.507Z,1347989485.507 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=46
2012-09-18T17:31:26.355Z,1347989486.355 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=24
2012-09-18T17:31:27.191Z,1347989487.191 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:28.067Z,1347989488.067 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:28.102Z,1347989488.102 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:28.968Z,1347989488.968 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=180
2012-09-18T17:31:29.791Z,1347989489.792 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=31
2012-09-18T17:31:30.067Z,1347989490.067 [DVL_micro](DEBUG): RSSI read bytes: 2464 of 9592, #buffered=0
2012-09-18T17:31:30.631Z,1347989490.631 [DVL_micro](DEBUG): RSSI read bytes: 7128 of 7128, #buffered=72
2012-09-18T17:31:30.899Z,1347989490.899 [CommandLine](IMPORTANT): got command report touch DVL_micro.durationOfLastRun
2012-09-18T17:31:31.471Z,1347989491.471 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:31.743Z,1347989491.743 [Reporter](INFO): DVL_micro.durationOfLastRun 0.062100 s
2012-09-18T17:31:32.399Z,1347989492.399 [DVL_micro](DEBUG): NQ1
2012-09-18T17:31:32.422Z,1347989492.422 [DVL_micro](DEBUG): ADCP
2012-09-18T17:31:32.924Z,1347989492.924 [Reporter](INFO): DVL_micro.durationOfLastRun 0.019800 s
2012-09-18T17:31:33.280Z,1347989493.280 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=33
2012-09-18T17:31:34.119Z,1347989494.119 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=41
2012-09-18T17:31:34.968Z,1347989494.968 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=178
2012-09-18T17:31:35.363Z,1347989495.363 [CommandLine](IMPORTANT): got command stop
2012-09-18T17:31:35.551Z,1347989495.551 [science_to_67_70] Stopped
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:AltEnvWrapper] Stopped
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:B.DepthEnvelope] Stopped
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-09-18T17:31:35.552Z,1347989495.552 [science_to_67_70:OffshoreWrapper] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:A] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:B] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:C] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:D] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:E] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:F] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:G] Stopped
2012-09-18T17:31:35.553Z,1347989495.553 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-09-18T17:31:35.554Z,1347989495.554 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-09-18T17:31:35.554Z,1347989495.554 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-09-18T17:31:35.554Z,1347989495.554 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-09-18T17:31:35.554Z,1347989495.554 [science_to_67_70:G.Buoyancy] Stopped
2012-09-18T17:31:35.554Z,1347989495.554 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-09-18T17:31:35.563Z,1347989495.563 [science_to_67_70:H.SetSpeed] Stopped
2012-09-18T17:31:35.563Z,1347989495.563 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-09-18T17:31:35.563Z,1347989495.563 [science_to_67_70:I.DepthEnvelope] Stopped
2012-09-18T17:31:35.563Z,1347989495.563 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-09-18T17:31:35.564Z,1347989495.564 [science_to_67_70:J.YoYo] Stopped
2012-09-18T17:31:35.564Z,1347989495.564 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-09-18T17:31:35.564Z,1347989495.564 [science_to_67_70:Wpt.Point] Stopped
2012-09-18T17:31:35.691Z,1347989495.691 [WetLabsBB2FL](INFO): Powering down
2012-09-18T17:31:35.791Z,1347989495.791 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-18T17:31:35.900Z,1347989495.900 [DVL_micro](INFO): pause:Powering down
2012-09-18T17:31:36.109Z,1347989496.109 [MissionManager](IMPORTANT): Started mission Default
2012-09-18T17:31:36.110Z,1347989496.110 [Default] Running Loop=1
2012-09-18T17:31:36.110Z,1347989496.110 [Default](INFO): Aggregate::initialize Default
2012-09-18T17:31:36.110Z,1347989496.110 [Default:D.SetSpeed] Running Loop=1
2012-09-18T17:31:36.110Z,1347989496.110 [Default:D.SetSpeed](DEBUG): Initialize.
2012-09-18T17:31:36.114Z,1347989496.114 [Default:E.GoToSurface] Running Loop=1
2012-09-18T17:31:36.114Z,1347989496.114 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-18T17:31:36.115Z,1347989496.115 [Default:Iridium] Running Loop=1
2012-09-18T17:31:36.115Z,1347989496.115 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-09-18T17:31:36.115Z,1347989496.115 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-18T17:31:36.115Z,1347989496.115 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-09-18T17:31:36.115Z,1347989496.115 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-09-18T17:31:36.115Z,1347989496.115 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-18T17:31:36.116Z,1347989496.116 [Default:CallIridium] Running Loop=1
2012-09-18T17:31:36.117Z,1347989496.117 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-09-18T17:31:36.117Z,1347989496.117 [Default:CallIridium:A] Running Loop=1
2012-09-18T17:31:36.117Z,1347989496.117 [Default:CallIridium:A] Stopped
2012-09-18T17:31:36.117Z,1347989496.117 [Default:CallIridium:B] Running Loop=1
2012-09-18T17:31:36.117Z,1347989496.117 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-09-18T17:31:36.122Z,1347989496.122 [Default:Iridium:B.GoToSurface] Stopped
2012-09-18T17:31:36.123Z,1347989496.122 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-18T17:31:36.123Z,1347989496.123 [Default:Iridium:Read_Iridium] Running Loop=1
2012-09-18T17:31:36.162Z,1347989496.162 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:36.492Z,1347989496.492 [Default:GPS] Running Loop=1
2012-09-18T17:31:36.492Z,1347989496.492 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-09-18T17:31:36.492Z,1347989496.492 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-18T17:31:36.492Z,1347989496.492 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-09-18T17:31:36.492Z,1347989496.492 [Default:GPS:B.GoToSurface] Running Loop=1
2012-09-18T17:31:36.493Z,1347989496.493 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-18T17:31:36.493Z,1347989496.493 [Default:GPS:B.GoToSurface] Stopped
2012-09-18T17:31:36.493Z,1347989496.493 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-18T17:31:36.493Z,1347989496.493 [Default:GPS:Read_GPS] Running Loop=1
2012-09-18T17:31:36.629Z,1347989496.629 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:36.867Z,1347989496.867 [NAL9602](INFO): Powering up
2012-09-18T17:31:36.936Z,1347989496.936 [Default:GPS:Read_GPS] Stopped
2012-09-18T17:31:36.936Z,1347989496.936 [Default:GPS:D] Running Loop=1
2012-09-18T17:31:36.997Z,1347989496.997 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:37.287Z,1347989497.287 [Default:GPS:D] Stopped
2012-09-18T17:31:37.288Z,1347989497.288 [Default:GPS](INFO): Completed Default:GPS
2012-09-18T17:31:37.288Z,1347989497.288 [Default:GPS] Stopped
2012-09-18T17:31:37.288Z,1347989497.288 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-09-18T17:31:37.288Z,1347989497.288 [Default:GPS:A.SetSpeed] Stopped
2012-09-18T17:31:37.288Z,1347989497.288 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-09-18T17:31:37.374Z,1347989497.374 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:37.782Z,1347989497.782 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:38.186Z,1347989498.186 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:38.538Z,1347989498.538 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:38.978Z,1347989498.978 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:39.394Z,1347989499.394 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:39.750Z,1347989499.750 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:40.206Z,1347989500.206 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:40.590Z,1347989500.589 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:41.041Z,1347989501.042 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:41.328Z,1347989501.328 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:41.747Z,1347989501.747 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:42.119Z,1347989502.119 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:42.574Z,1347989502.574 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:42.974Z,1347989502.974 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:43.319Z,1347989503.319 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:43.778Z,1347989503.778 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:44.162Z,1347989504.162 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:44.518Z,1347989504.518 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:44.979Z,1347989504.978 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:45.338Z,1347989505.338 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:45.726Z,1347989505.726 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:46.167Z,1347989506.167 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:46.539Z,1347989506.539 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:46.919Z,1347989506.919 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:47.379Z,1347989507.378 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:47.723Z,1347989507.723 [NAL9602](INFO): NAL9602 initialized
2012-09-18T17:31:47.795Z,1347989507.795 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:48.111Z,1347989508.111 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:48.547Z,1347989508.547 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:49.454Z,1347989509.454 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:50.050Z,1347989510.050 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:50.438Z,1347989510.438 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:50.811Z,1347989510.811 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:51.238Z,1347989511.238 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:51.674Z,1347989511.674 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:52.031Z,1347989512.031 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:52.426Z,1347989512.426 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:52.854Z,1347989512.855 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:53.731Z,1347989513.731 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:56.315Z,1347989516.315 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:56.635Z,1347989516.635 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:57.322Z,1347989517.322 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:57.711Z,1347989517.711 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:58.074Z,1347989518.074 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:58.514Z,1347989518.514 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:58.915Z,1347989518.915 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:59.274Z,1347989519.274 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:31:59.711Z,1347989519.711 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:00.127Z,1347989520.127 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:00.471Z,1347989520.471 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:00.942Z,1347989520.943 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:01.307Z,1347989521.307 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:01.675Z,1347989521.675 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:02.071Z,1347989522.070 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:02.531Z,1347989522.531 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:02.874Z,1347989522.874 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:03.319Z,1347989523.319 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:03.719Z,1347989523.719 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:04.071Z,1347989524.071 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:04.523Z,1347989524.523 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:04.903Z,1347989524.903 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:05.274Z,1347989525.274 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:05.718Z,1347989525.718 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:06.107Z,1347989526.107 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:06.475Z,1347989526.475 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:06.919Z,1347989526.918 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:07.342Z,1347989527.342 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:07.675Z,1347989527.675 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:08.032Z,1347989528.033 [NAL9602](INFO): SBD MO Status=2, MOMSN=1285, MT Status=2, MTMSN=0
2012-09-18T17:32:08.033Z,1347989528.033 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-09-18T17:32:08.162Z,1347989528.162 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:08.511Z,1347989528.511 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:08.875Z,1347989528.875 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:09.302Z,1347989529.302 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:09.711Z,1347989529.711 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:10.074Z,1347989530.074 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:10.474Z,1347989530.474 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:10.915Z,1347989530.915 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:11.271Z,1347989531.271 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:11.722Z,1347989531.722 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:12.122Z,1347989532.122 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:12.983Z,1347989532.983 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:13.203Z,1347989533.203 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:13.551Z,1347989533.551 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:13.975Z,1347989533.974 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:14.399Z,1347989534.399 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:15.266Z,1347989535.266 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:15.834Z,1347989535.834 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:16.278Z,1347989536.278 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:16.665Z,1347989536.665 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:17.038Z,1347989537.038 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:17.482Z,1347989537.483 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:17.875Z,1347989537.875 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:18.243Z,1347989538.243 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:18.706Z,1347989538.706 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:19.575Z,1347989539.575 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:22.150Z,1347989542.151 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:22.422Z,1347989542.422 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:23.115Z,1347989543.115 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:23.507Z,1347989543.507 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:23.927Z,1347989543.927 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:24.346Z,1347989544.346 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:24.750Z,1347989544.750 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:25.115Z,1347989545.115 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:25.539Z,1347989545.539 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:25.919Z,1347989545.919 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:26.315Z,1347989546.315 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:26.842Z,1347989546.842 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:27.543Z,1347989547.543 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:27.934Z,1347989547.934 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:28.319Z,1347989548.319 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:28.734Z,1347989548.734 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:29.118Z,1347989549.118 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:29.630Z,1347989549.630 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:29.911Z,1347989549.911 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:30.115Z,1347989550.115 [CommandLine](IMPORTANT): got command load ./Missions/Science/science_to_67_70.xml
2012-09-18T17:32:30.115Z,1347989550.115 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml
2012-09-18T17:32:30.287Z,1347989550.287 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m
2012-09-18T17:32:30.289Z,1347989550.289 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m
2012-09-18T17:32:30.300Z,1347989550.300 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m
2012-09-18T17:32:30.302Z,1347989550.302 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m
2012-09-18T17:32:30.304Z,1347989550.304 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m
2012-09-18T17:32:30.318Z,1347989550.318 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg
2012-09-18T17:32:30.321Z,1347989550.321 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg
2012-09-18T17:32:30.323Z,1347989550.323 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg
2012-09-18T17:32:30.325Z,1347989550.325 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg
2012-09-18T17:32:30.340Z,1347989550.340 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min
2012-09-18T17:32:30.342Z,1347989550.342 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s
2012-09-18T17:32:30.344Z,1347989550.344 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h
2012-09-18T17:32:30.359Z,1347989550.359 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a
2012-09-18T17:32:30.361Z,1347989550.361 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-09-18T17:32:30.366Z,1347989550.366 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-09-18T17:32:30.379Z,1347989550.379 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-09-18T17:32:30.386Z,1347989550.385 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-09-18T17:32:30.398Z,1347989550.398 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-09-18T17:32:30.399Z,1347989550.399 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-09-18T17:32:30.472Z,1347989550.472 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-09-18T17:32:30.478Z,1347989550.478 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:30.502Z,1347989550.503 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-09-18T17:32:30.512Z,1347989550.512 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-09-18T17:32:30.515Z,1347989550.515 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-09-18T17:32:30.516Z,1347989550.516 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-18T17:32:30.532Z,1347989550.532 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2012-09-18T17:32:30.553Z,1347989550.553 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-09-18T17:32:30.718Z,1347989550.718 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a
2012-09-18T17:32:30.720Z,1347989550.720 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool
2012-09-18T17:32:30.722Z,1347989550.722 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool
2012-09-18T17:32:30.740Z,1347989550.740 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool
2012-09-18T17:32:30.742Z,1347989550.742 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 1.000000 n/a
2012-09-18T17:32:30.745Z,1347989550.745 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a
2012-09-18T17:32:30.763Z,1347989550.763 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a
2012-09-18T17:32:30.765Z,1347989550.765 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool
2012-09-18T17:32:30.767Z,1347989550.767 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool
2012-09-18T17:32:30.769Z,1347989550.769 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a
2012-09-18T17:32:30.792Z,1347989550.792 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l
2012-09-18T17:32:30.795Z,1347989550.795 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m
2012-09-18T17:32:30.797Z,1347989550.797 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg
2012-09-18T17:32:30.816Z,1347989550.816 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg
2012-09-18T17:32:30.818Z,1347989550.818 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l
2012-09-18T17:32:30.820Z,1347989550.820 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m
2012-09-18T17:32:30.835Z,1347989550.835 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg
2012-09-18T17:32:30.837Z,1347989550.837 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg
2012-09-18T17:32:30.859Z,1347989550.859 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-09-18T17:32:30.879Z,1347989550.879 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-09-18T17:32:30.898Z,1347989550.898 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool
2012-09-18T17:32:30.899Z,1347989550.899 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool
2012-09-18T17:32:30.900Z,1347989550.900 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy.
2012-09-18T17:32:30.915Z,1347989550.915 [science_to_67_70:H.SetSpeed](DEBUG): Construct.
2012-09-18T17:32:30.917Z,1347989550.917 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-09-18T17:32:30.936Z,1347989550.936 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo.
2012-09-18T17:32:30.941Z,1347989550.941 [science_to_67_70:Wpt.Point](DEBUG): Construct.
2012-09-18T17:32:30.998Z,1347989550.998 [MissionManager](DEBUG):
Vehicle yo-yo's to the specified waypoint, with science turned on.
Minimum height above the sea floor for the entire mission.
10.0
Maximum depth for the entire mission.
105.0
Minimum offshore distance for the entire mission.
2000.0
Minimum depth while performing the YoYo behavior.
3.0
Maximum depth while performing the YoYo behavior.
100.0
Vehicle up pitch while performing the YoYo behavior.
20.0
Vehicle down pitch while performing the YoYo behavior.
-15.0
Latitude of waypoint.
36.120
Longitude of waypoint.
-123.486
Elapsed time after previous surface communications when vehicle will
begin to ascend for additional surface communications, if the vehicle
has a positive pitch. If the vehicle has a negative pitch, it will wait
up to 10 minutes more to begin ascent.
120
Speed while performing the YoYo behavior.
1
Maximum length of mission
36
Buoyancy bladder position while performing the YoYo behavior. Defaults
to buoyancyNeutral setting in the Config/Control.cfg file.
0
Safety behavior that keeps vehicle off sea floor
Safety behavior that keeps vehicle from going too deep
0
Safety behavior that keeps vehicle away from the shore
20
0
10
If we're executing here or below, lets' get science data!
2012-09-18T17:32:30.998Z,1347989550.999 [CommandLine](IMPORTANT): Loaded ./Missions/Science/science_to_67_70.xml
2012-09-18T17:32:30.999Z,1347989550.999 [CommandLine](IMPORTANT): got command set science_to_67_70.WptLat 36.308430 degree
2012-09-18T17:32:30.000Z,1347989551.000 [CommandLine](IMPORTANT): got command set science_to_67_70.WptLon -122.055359 degree
2012-09-18T17:32:31.001Z,1347989551.001 [CommandLine](IMPORTANT): got command set science_to_67_70.NeedCommsTime 60.000000 minute
2012-09-18T17:32:31.001Z,1347989551.001 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleAanderaaO2 0.000000 bool
2012-09-18T17:32:31.014Z,1347989551.014 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleISUS 0.000000 bool
2012-09-18T17:32:31.015Z,1347989551.015 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleNeilBrown 1.000000 bool
2012-09-18T17:32:31.016Z,1347989551.016 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.SampleWetLabsBB2FL 1.000000 bool
2012-09-18T17:32:31.016Z,1347989551.016 [CommandLine](IMPORTANT): got command set science_to_67_70:Science.EnabledDVL 1.000000 bool
2012-09-18T17:32:31.017Z,1347989551.017 [CommandLine](IMPORTANT): got command run
2012-09-18T17:32:31.017Z,1347989551.017 [CommandLine](IMPORTANT): Running
2012-09-18T17:32:31.065Z,1347989551.065 [Default] Stopped
2012-09-18T17:32:31.065Z,1347989551.065 [Default](INFO): Aggregate::uninitialize Default
2012-09-18T17:32:31.065Z,1347989551.065 [Default:Iridium] Stopped
2012-09-18T17:32:31.065Z,1347989551.065 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-09-18T17:32:31.065Z,1347989551.065 [Default:Iridium:A.SetSpeed] Stopped
2012-09-18T17:32:31.065Z,1347989551.065 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-09-18T17:32:31.065Z,1347989551.065 [Default:Iridium:Read_Iridium] Stopped
2012-09-18T17:32:31.065Z,1347989551.066 [Default:CallIridium] Stopped
2012-09-18T17:32:31.066Z,1347989551.066 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-09-18T17:32:31.066Z,1347989551.066 [Default:CallIridium:B] Stopped
2012-09-18T17:32:31.066Z,1347989551.066 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-09-18T17:32:31.066Z,1347989551.066 [Default:D.SetSpeed] Stopped
2012-09-18T17:32:31.066Z,1347989551.066 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-09-18T17:32:31.066Z,1347989551.066 [Default:E.GoToSurface] Stopped
2012-09-18T17:32:31.066Z,1347989551.066 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-18T17:32:31.066Z,1347989551.066 [MissionManager](IMPORTANT): Started mission science_to_67_70
2012-09-18T17:32:31.067Z,1347989551.066 [science_to_67_70] Running Loop=1
2012-09-18T17:32:31.067Z,1347989551.067 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70
2012-09-18T17:32:31.067Z,1347989551.067 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-09-18T17:32:31.067Z,1347989551.067 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-09-18T17:32:31.067Z,1347989551.067 [science_to_67_70:Science] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science:A] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science:B] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science:C] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science:D] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science:E] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science:F] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:Science:G] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-09-18T17:32:31.068Z,1347989551.068 [science_to_67_70:H.SetSpeed](DEBUG): Initialize.
2012-09-18T17:32:31.068Z,1347989551.069 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-09-18T17:32:31.069Z,1347989551.069 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-09-18T17:32:31.069Z,1347989551.069 [science_to_67_70:J.YoYo] Running Loop=1
2012-09-18T17:32:31.069Z,1347989551.069 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent.
2012-09-18T17:32:31.070Z,1347989551.070 [science_to_67_70:SURFACECOMMS] Running Loop=1
2012-09-18T17:32:31.070Z,1347989551.070 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS
2012-09-18T17:32:31.070Z,1347989551.070 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-09-18T17:32:31.070Z,1347989551.070 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-18T17:32:31.071Z,1347989551.071 [science_to_67_70:J.YoYo] Running Loop=1
2012-09-18T17:32:31.072Z,1347989551.072 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-09-18T17:32:31.073Z,1347989551.073 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-09-18T17:32:31.082Z,1347989551.082 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-09-18T17:32:31.083Z,1347989551.083 [science_to_67_70:Science] Running Loop=1
2012-09-18T17:32:31.083Z,1347989551.083 [science_to_67_70:Science:PeakDetectChl] Running Loop=1
2012-09-18T17:32:31.084Z,1347989551.084 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl
2012-09-18T17:32:31.084Z,1347989551.084 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2012-09-18T17:32:31.084Z,1347989551.084 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize.
2012-09-18T17:32:31.086Z,1347989551.086 [science_to_67_70:Science:G] Running Loop=1
2012-09-18T17:32:31.086Z,1347989551.086 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-09-18T17:32:31.087Z,1347989551.087 [science_to_67_70:Science:F] Running Loop=1
2012-09-18T17:32:31.088Z,1347989551.088 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI
2012-09-18T17:32:31.089Z,1347989551.089 [science_to_67_70:Science:E] Running Loop=1
2012-09-18T17:32:31.089Z,1347989551.089 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity
2012-09-18T17:32:31.090Z,1347989551.090 [science_to_67_70:Science:D] Running Loop=1
2012-09-18T17:32:31.103Z,1347989551.103 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond
2012-09-18T17:32:31.104Z,1347989551.103 [science_to_67_70:Science:C] Running Loop=1
2012-09-18T17:32:31.104Z,1347989551.104 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-09-18T17:32:31.105Z,1347989551.105 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-09-18T17:32:31.106Z,1347989551.106 [WetLabsBB2FL](INFO): Powering up
2012-09-18T17:32:31.111Z,1347989551.111 [science_to_67_70:SURFACECOMMS:B] Running Loop=1
2012-09-18T17:32:31.111Z,1347989551.111 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B
2012-09-18T17:32:31.112Z,1347989551.112 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1
2012-09-18T17:32:31.112Z,1347989551.112 [science_to_67_70:OffshoreWrapper] Running Loop=1
2012-09-18T17:32:31.112Z,1347989551.112 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper
2012-09-18T17:32:31.112Z,1347989551.112 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-09-18T17:32:31.112Z,1347989551.112 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-09-18T17:32:31.112Z,1347989551.112 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1
2012-09-18T17:32:31.112Z,1347989551.112 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-09-18T17:32:31.113Z,1347989551.113 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-09-18T17:32:31.118Z,1347989551.118 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-09-18T17:32:31.119Z,1347989551.119 [science_to_67_70:AltEnvWrapper] Running Loop=1
2012-09-18T17:32:31.120Z,1347989551.120 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper
2012-09-18T17:32:31.120Z,1347989551.120 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-09-18T17:32:31.120Z,1347989551.120 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-09-18T17:32:31.120Z,1347989551.120 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1
2012-09-18T17:32:31.120Z,1347989551.120 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-09-18T17:32:31.121Z,1347989551.121 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-09-18T17:32:31.182Z,1347989551.182 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:31.279Z,1347989551.279 [DVL_micro](INFO): resume:Powering up
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:J.YoYo] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:I.DepthEnvelope] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:H.SetSpeed] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:G.Buoyancy] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:Science] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:Science:C] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:Science:D] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:Science:E] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:Science:F] Preempted
2012-09-18T17:32:31.864Z,1347989551.864 [science_to_67_70:Science:G] Preempted
2012-09-18T17:32:31.865Z,1347989551.865 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-18T17:32:31.866Z,1347989551.866 [science_to_67_70:SURFACECOMMS:B:A] Stopped
2012-09-18T17:32:31.870Z,1347989551.870 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1
2012-09-18T17:32:31.915Z,1347989551.915 [Reporter](INFO): DVL_micro.durationOfLastRun no_value
2012-09-18T17:32:32.226Z,1347989552.226 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-18T17:32:32.298Z,1347989552.298 [WetLabsBB2FL](INFO): Powering down
2012-09-18T17:32:32.786Z,1347989552.786 [DVL_micro](INFO): Querying output modes
2012-09-18T17:32:32.787Z,1347989552.787 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606
2012-09-18T17:32:32.790Z,1347989552.790 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-09-18T17:32:32.791Z,1347989552.791 [DVL_micro](INFO): NQ1 output enabled
2012-09-18T17:32:32.791Z,1347989552.791 [DVL_micro](INFO): RSSI output enabled
2012-09-18T17:32:32.791Z,1347989552.791 [DVL_micro](INFO): ADCP output enabled
2012-09-18T17:32:32.791Z,1347989552.791 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525
2012-09-18T17:32:32.799Z,1347989552.799 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON
2012-09-18T17:32:32.800Z,1347989552.800 [DVL_micro](INFO): RSSI unrequested
2012-09-18T17:32:32.800Z,1347989552.800 [DVL_micro](INFO): ADCP unrequested
2012-09-18T17:32:33.071Z,1347989553.071 [Reporter](INFO): DVL_micro.durationOfLastRun 0.000500 s
2012-09-18T17:32:38.976Z,1347989558.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=1285, MT Status=2, MTMSN=0
2012-09-18T17:32:38.977Z,1347989558.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-09-18T17:32:52.343Z,1347989572.343 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-09-18T17:32:52.426Z,1347989572.426 [science_to_67_70:SURFACECOMMS:B:B] Stopped
2012-09-18T17:32:52.434Z,1347989572.434 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1
2012-09-18T17:32:52.836Z,1347989572.836 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-18T17:32:52.837Z,1347989572.837 [science_to_67_70:SURFACECOMMS:B:C] Stopped
2012-09-18T17:32:52.837Z,1347989572.837 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B
2012-09-18T17:32:52.837Z,1347989572.837 [science_to_67_70:SURFACECOMMS:B] Stopped
2012-09-18T17:32:52.837Z,1347989572.837 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B
2012-09-18T17:32:52.838Z,1347989572.838 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS
2012-09-18T17:32:52.846Z,1347989572.846 [science_to_67_70:SURFACECOMMS] Stopped
2012-09-18T17:32:52.846Z,1347989572.846 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS
2012-09-18T17:32:52.846Z,1347989572.846 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped
2012-09-18T17:32:52.846Z,1347989572.846 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-18T17:32:52.847Z,1347989572.847 [science_to_67_70:Wpt.Point] Running Loop=1
2012-09-18T17:32:52.847Z,1347989572.847 [science_to_67_70:Wpt.Point](DEBUG): Initialize.
2012-09-18T17:32:53.256Z,1347989573.256 [science_to_67_70:J.YoYo] Running Loop=1
2012-09-18T17:32:53.257Z,1347989573.257 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-09-18T17:32:53.257Z,1347989573.257 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-09-18T17:32:53.258Z,1347989573.258 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-09-18T17:32:53.266Z,1347989573.266 [science_to_67_70:Science] Running Loop=1
2012-09-18T17:32:53.267Z,1347989573.267 [science_to_67_70:Science:G] Running Loop=1
2012-09-18T17:32:53.267Z,1347989573.267 [science_to_67_70:Science:F] Running Loop=1
2012-09-18T17:32:53.268Z,1347989573.268 [science_to_67_70:Science:E] Running Loop=1
2012-09-18T17:32:53.268Z,1347989573.268 [science_to_67_70:Science:D] Running Loop=1
2012-09-18T17:32:53.268Z,1347989573.268 [science_to_67_70:Science:C] Running Loop=1
2012-09-18T17:32:53.315Z,1347989573.315 [WetLabsBB2FL](INFO): Powering up
2012-09-18T17:32:56.047Z,1347989576.047 [CTD_NeilBrown](ERROR): Salinity reading out of range: -260.530670 psu
2012-09-18T17:33:01.771Z,1347989581.771 [NAL9602](INFO): Powering down
2012-09-18T17:33:22.287Z,1347989602.287 [CommandLine](IMPORTANT): got command report
2012-09-18T17:33:22.287Z,1347989602.287 [Reporter](INFO): DVL_micro.durationOfLastRun reporting when touched
2012-09-18T17:33:54.731Z,1347989634.731 [CommandLine](IMPORTANT): got command show variable CBIT
2012-09-18T17:33:54.734Z,1347989634.734 [CommandLine](INFO): CBIT.GFActive (bool)
2012-09-18T17:33:54.745Z,1347989634.745 [CommandLine](INFO): Config/BIT.CBIT_enable (bool)
2012-09-18T17:33:54.745Z,1347989634.745 [CommandLine](INFO): Config/BIT.CBIT_useHardware (bool)
2012-09-18T17:33:54.746Z,1347989634.746 [CommandLine](INFO): CBIT.abortDepth (meter)
2012-09-18T17:33:54.746Z,1347989634.746 [CommandLine](INFO): CBIT.stopDepth (meter)
2012-09-18T17:33:54.747Z,1347989634.747 [CommandLine](INFO): CBIT.humidityThreshold (percent)
2012-09-18T17:33:54.747Z,1347989634.747 [CommandLine](INFO): CBIT.pressureThreshold (pound_per_square_inch)
2012-09-18T17:33:54.747Z,1347989634.747 [CommandLine](INFO): CBIT.tempThreshold (fahrenheit)
2012-09-18T17:33:54.748Z,1347989634.748 [CommandLine](INFO): CBIT.vehicleOpen (bool)
2012-09-18T17:33:54.748Z,1347989634.748 [CommandLine](INFO): CBIT.abortDepthTimeout (second)
2012-09-18T17:33:54.748Z,1347989634.749 [CommandLine](INFO): CBIT.battFailReport (count)
2012-09-18T17:33:54.749Z,1347989634.749 [CommandLine](INFO): CBIT.envTimeout (second)
2012-09-18T17:33:54.749Z,1347989634.749 [CommandLine](INFO): CBIT.battTempThreshold (celsius)
2012-09-18T17:33:54.749Z,1347989634.750 [CommandLine](INFO): CBIT.gfChan0_Threshold (milliampere)
2012-09-18T17:33:54.750Z,1347989634.750 [CommandLine](INFO): CBIT.gfChan1_Threshold (milliampere)
2012-09-18T17:33:54.750Z,1347989634.750 [CommandLine](INFO): CBIT.gfChan2_Threshold (milliampere)
2012-09-18T17:33:54.751Z,1347989634.751 [CommandLine](INFO): CBIT.gfChan4_Threshold (milliampere)
2012-09-18T17:33:54.751Z,1347989634.751 [CommandLine](INFO): CBIT.gfChan5_Threshold (milliampere)
2012-09-18T17:33:54.752Z,1347989634.751 [CommandLine](INFO): CBIT.gfScanTimeout (hour)
2012-09-18T17:33:54.792Z,1347989634.792 [CommandLine](INFO): CBIT.shorePowerOn (bool)
2012-09-18T17:33:54.793Z,1347989634.793 [CommandLine](INFO): CBIT.platform_fault (enum)
2012-09-18T17:33:54.793Z,1347989634.793 [CommandLine](INFO): CBIT.platform_fault_leak (enum)
2012-09-18T17:33:54.794Z,1347989634.794 [CommandLine](INFO): CBIT.GFCHAN0Current (milliampere)
2012-09-18T17:33:54.807Z,1347989634.807 [CommandLine](INFO): CBIT.GFCHAN1Current (milliampere)
2012-09-18T17:33:54.808Z,1347989634.808 [CommandLine](INFO): CBIT.GFCHAN2Current (milliampere)
2012-09-18T17:33:54.808Z,1347989634.808 [CommandLine](INFO): CBIT.GFCHAN4Current (milliampere)
2012-09-18T17:33:54.809Z,1347989634.809 [CommandLine](INFO): CBIT.GFCHAN5Current (milliampere)
2012-09-18T17:33:54.809Z,1347989634.809 [CommandLine](INFO): CBIT.GFCHANOpenCurrent (milliampere)
2012-09-18T17:33:54.868Z,1347989634.868 [CommandLine](INFO): CBIT.durationOfLastRun (second)
2012-09-18T17:34:02.164Z,1347989642.164 [CommandLine](IMPORTANT): got command report touch CBIT.durationOfLastRun
2012-09-18T17:34:02.626Z,1347989642.626 [Reporter](INFO): CBIT.durationOfLastRun 0.001000 s
2012-09-18T17:34:03.616Z,1347989643.616 [Reporter](INFO): CBIT.durationOfLastRun 0.001000 s
2012-09-18T17:34:04.521Z,1347989644.522 [Reporter](INFO): CBIT.durationOfLastRun 0.007400 s
2012-09-18T17:34:05.426Z,1347989645.426 [Reporter](INFO): CBIT.durationOfLastRun 0.001000 s
2012-09-18T17:34:06.232Z,1347989646.232 [Reporter](INFO): CBIT.durationOfLastRun 0.001100 s
2012-09-18T17:34:07.230Z,1347989647.230 [Reporter](INFO): CBIT.durationOfLastRun 0.000900 s
2012-09-18T17:34:08.013Z,1347989648.013 [Reporter](INFO): CBIT.durationOfLastRun 0.005900 s
2012-09-18T17:34:08.727Z,1347989648.727 [Reporter](INFO): CBIT.durationOfLastRun 0.000900 s
2012-09-18T17:34:09.456Z,1347989649.455 [Reporter](INFO): CBIT.durationOfLastRun 0.001000 s
2012-09-18T17:34:10.383Z,1347989650.383 [CTD_NeilBrown](ERROR): Bad response: 44.49845
2012-09-18T17:34:10.504Z,1347989650.504 [Reporter](INFO): CBIT.durationOfLastRun 0.001100 s
2012-09-18T17:34:11.323Z,1347989651.323 [Reporter](INFO): CBIT.durationOfLastRun 0.001200 s
2012-09-18T17:34:12.013Z,1347989652.013 [Reporter](INFO): CBIT.durationOfLastRun 0.002400 s
2012-09-18T17:34:12.731Z,1347989652.731 [Reporter](INFO): CBIT.durationOfLastRun 0.000800 s
2012-09-18T17:34:13.471Z,1347989653.471 [Reporter](INFO): CBIT.durationOfLastRun 0.000900 s
2012-09-18T17:34:14.384Z,1347989654.384 [Reporter](INFO): CBIT.durationOfLastRun 0.001000 s
2012-09-18T17:34:15.066Z,1347989655.066 [Reporter](INFO): CBIT.durationOfLastRun 0.000800 s
2012-09-18T17:34:15.746Z,1347989655.746 [Reporter](INFO): CBIT.durationOfLastRun 0.000900 s
2012-09-18T17:34:16.408Z,1347989656.408 [Reporter](INFO): CBIT.durationOfLastRun 0.001900 s
2012-09-18T17:34:17.254Z,1347989657.254 [Reporter](INFO): CBIT.durationOfLastRun 0.000800 s
2012-09-18T17:34:18.062Z,1347989658.062 [Reporter](INFO): CBIT.durationOfLastRun 0.001000 s
2012-09-18T17:34:18.774Z,1347989658.774 [Reporter](INFO): CBIT.durationOfLastRun 0.000900 s
2012-09-18T17:34:19.430Z,1347989659.430 [Reporter](INFO): CBIT.durationOfLastRun 0.000900 s
2012-09-18T17:34:20.132Z,1347989660.132 [Reporter](INFO): CBIT.durationOfLastRun 0.001200 s
2012-09-18T17:34:21.079Z,1347989661.079 [Reporter](INFO): CBIT.durationOfLastRun 0.000900 s
2012-09-18T17:34:21.823Z,1347989661.823 [Reporter](INFO): CBIT.durationOfLastRun 0.001200 s
2012-09-18T17:34:22.533Z,1347989662.533 [Reporter](INFO): CBIT.durationOfLastRun 0.002200 s
2012-09-18T17:34:23.247Z,1347989663.247 [Reporter](INFO): CBIT.durationOfLastRun 0.000800 s
2012-09-18T17:34:23.859Z,1347989663.859 [CommandLine](IMPORTANT): got command report clear
2012-09-18T17:34:27.131Z,1347989667.131 [CommandLine](IMPORTANT): got command show variable DVL
2012-09-18T17:34:27.135Z,1347989667.135 [CommandLine](INFO): Config/vehicle.DVL_micro_card (none)
2012-09-18T17:34:27.135Z,1347989667.135 [CommandLine](INFO): Config/vehicle.DVL_micro_uart (none)
2012-09-18T17:34:27.136Z,1347989667.136 [CommandLine](INFO): Config/vehicle.DVL_micro_baud (bit_per_second)
2012-09-18T17:34:27.140Z,1347989667.139 [CommandLine](INFO): Config/Sensor.DVL_micro_enable (bool)
2012-09-18T17:34:27.140Z,1347989667.140 [CommandLine](INFO): Config/Sensor.DVL_micro_useHardware (bool)
2012-09-18T17:34:27.140Z,1347989667.140 [CommandLine](INFO): Config/Sensor.DVL_micro_magDeviation (degree)
2012-09-18T17:34:27.141Z,1347989667.141 [CommandLine](INFO): Config/Sensor.DVL_micro_pitchOffset (degree)
2012-09-18T17:34:27.141Z,1347989667.141 [CommandLine](INFO): Config/Sensor.DVL_micro_rollOffset (degree)
2012-09-18T17:34:27.141Z,1347989667.141 [CommandLine](INFO): Config/Sensor.DVL_micro_power (watt)
2012-09-18T17:34:27.161Z,1347989667.161 [CommandLine](INFO): DVL_micro.height_above_sea_floor (meter)
2012-09-18T17:34:27.161Z,1347989667.161 [CommandLine](INFO): DVL_micro.platform_orientation (degree)
2012-09-18T17:34:27.162Z,1347989667.161 [CommandLine](INFO): DVL_micro.platform_magnetic_orientation (degree)
2012-09-18T17:34:27.162Z,1347989667.162 [CommandLine](INFO): DVL_micro.platform_pitch_angle (degree)
2012-09-18T17:34:27.163Z,1347989667.163 [CommandLine](INFO): DVL_micro.platform_roll_angle (degree)
2012-09-18T17:34:27.164Z,1347989667.164 [CommandLine](INFO): DVL_micro.platform_speed_wrt_ground (meter_per_second)
2012-09-18T17:34:27.164Z,1347989667.164 [CommandLine](INFO): DVL_micro.platform_speed_wrt_sea_water (meter_per_second)
2012-09-18T17:34:27.165Z,1347989667.165 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_ground (meter_per_second)
2012-09-18T17:34:27.165Z,1347989667.165 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_ground (meter_per_second)
2012-09-18T17:34:27.165Z,1347989667.165 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_ground (meter_per_second)
2012-09-18T17:34:27.183Z,1347989667.183 [CommandLine](INFO): DVL_micro.platform_x_velocity_wrt_sea_water (meter_per_second)
2012-09-18T17:34:27.184Z,1347989667.184 [CommandLine](INFO): DVL_micro.platform_y_velocity_wrt_sea_water (meter_per_second)
2012-09-18T17:34:27.184Z,1347989667.184 [CommandLine](INFO): DVL_micro.platform_z_velocity_wrt_sea_water (meter_per_second)
2012-09-18T17:34:27.184Z,1347989667.184 [CommandLine](INFO): DVL_micro.Status (count)
2012-09-18T17:34:27.185Z,1347989667.185 [CommandLine](INFO): DVL_micro.Beam1Good (count)
2012-09-18T17:34:27.185Z,1347989667.185 [CommandLine](INFO): DVL_micro.Beam2Good (count)
2012-09-18T17:34:27.186Z,1347989667.186 [CommandLine](INFO): DVL_micro.Beam3Good (count)
2012-09-18T17:34:27.207Z,1347989667.207 [CommandLine](INFO): DVL_micro.Beam4Good (count)
2012-09-18T17:34:27.208Z,1347989667.208 [CommandLine](INFO): DVL_micro.Altitude1 (meter)
2012-09-18T17:34:27.209Z,1347989667.209 [CommandLine](INFO): DVL_micro.Altitude2 (meter)
2012-09-18T17:34:27.209Z,1347989667.209 [CommandLine](INFO): DVL_micro.Altitude3 (meter)
2012-09-18T17:34:27.209Z,1347989667.209 [CommandLine](INFO): DVL_micro.Altitude4 (meter)
2012-09-18T17:34:27.210Z,1347989667.210 [CommandLine](INFO): DVL_micro.BottomVelocityFlag (count)
2012-09-18T17:34:27.231Z,1347989667.231 [CommandLine](INFO): DVL_micro.WaterVelocityFlag (count)
2012-09-18T17:34:27.243Z,1347989667.243 [CommandLine](INFO): DVL_micro.Beam1RSSI (decibel)
2012-09-18T17:34:27.243Z,1347989667.243 [CommandLine](INFO): DVL_micro.Beam2RSSI (decibel)
2012-09-18T17:34:27.243Z,1347989667.243 [CommandLine](INFO): DVL_micro.Beam3RSSI (decibel)
2012-09-18T17:34:27.244Z,1347989667.244 [CommandLine](INFO): DVL_micro.Beam4RSSI (decibel)
2012-09-18T17:34:27.244Z,1347989667.244 [CommandLine](INFO): DVL_micro.RSSIFilterLength (count)
2012-09-18T17:34:27.244Z,1347989667.244 [CommandLine](INFO): DVL_micro.RSSIPoints (count)
2012-09-18T17:34:27.245Z,1347989667.245 [CommandLine](INFO): DVL_micro.AdcpNumBins (count)
2012-09-18T17:34:27.245Z,1347989667.245 [CommandLine](INFO): DVL_micro.AdcpStartDepth (meter)
2012-09-18T17:34:27.246Z,1347989667.246 [CommandLine](INFO): DVL_micro.AdcpXWaterVelocity (meter_per_second)
2012-09-18T17:34:27.250Z,1347989667.250 [CommandLine](INFO): DVL_micro.AdcpYWaterVelocity (meter_per_second)
2012-09-18T17:34:27.251Z,1347989667.251 [CommandLine](INFO): DVL_micro.AdcpZWaterVelocity (meter_per_second)
2012-09-18T17:34:27.253Z,1347989667.253 [CommandLine](INFO): DVL_micro.component_voltage (volt)
2012-09-18T17:34:27.253Z,1347989667.253 [CommandLine](INFO): DVL_micro.component_avgVoltage (volt)
2012-09-18T17:34:27.254Z,1347989667.254 [CommandLine](INFO): DVL_micro.component_current (milliampere)
2012-09-18T17:34:27.266Z,1347989667.266 [CommandLine](INFO): DVL_micro.component_avgCurrent (milliampere)
2012-09-18T17:34:27.268Z,1347989667.268 [CommandLine](INFO): DVL_micro.durationOfLastRun (second)
2012-09-18T17:34:27.312Z,1347989667.312 [CommandLine](INFO): science_to_67_70:Science.EnabledDVL (none)
2012-09-18T17:34:36.887Z,1347989676.887 [CommandLine](IMPORTANT): got command report touch DVL_micro.durationOfLastRun
2012-09-18T17:34:58.319Z,1347989698.319 [CTD_NeilBrown](ERROR): Bad response: 0.09
2012-09-18T17:35:22.751Z,1347989722.751 [CTD_NeilBrown](ERROR): Bad response: ,32.44682
2012-09-18T17:35:50.271Z,1347989750.271 [CTD_NeilBrown](ERROR): Bad response: 0.913
2012-09-18T17:38:52.475Z,1347989932.475 [CommandLine](IMPORTANT): got command quit
2012-09-18T17:38:53.635Z,1347989933.635 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2012-09-18T17:38:53.635Z,1347989933.635 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-09-18T17:38:54.025Z,1347989934.025 [science_to_67_70] Stopped
2012-09-18T17:38:54.025Z,1347989934.025 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-09-18T17:38:54.025Z,1347989934.025 [science_to_67_70:AltEnvWrapper] Stopped
2012-09-18T17:38:54.025Z,1347989934.025 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-09-18T17:38:54.025Z,1347989934.025 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-09-18T17:38:54.025Z,1347989934.025 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-09-18T17:38:54.025Z,1347989934.026 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-09-18T17:38:54.026Z,1347989934.026 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-09-18T17:38:54.026Z,1347989934.026 [science_to_67_70:B.DepthEnvelope] Stopped
2012-09-18T17:38:54.026Z,1347989934.026 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-09-18T17:38:54.026Z,1347989934.026 [science_to_67_70:OffshoreWrapper] Stopped
2012-09-18T17:38:54.026Z,1347989934.026 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-09-18T17:38:54.035Z,1347989934.035 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-09-18T17:38:54.035Z,1347989934.035 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science:A] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science:B] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science:C] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science:D] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science:E] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science:F] Stopped
2012-09-18T17:38:54.036Z,1347989934.036 [science_to_67_70:Science:G] Stopped
2012-09-18T17:38:54.037Z,1347989934.037 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-09-18T17:38:54.037Z,1347989934.037 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-09-18T17:38:54.037Z,1347989934.037 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-09-18T17:38:54.037Z,1347989934.037 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-09-18T17:38:54.037Z,1347989934.037 [science_to_67_70:G.Buoyancy] Stopped
2012-09-18T17:38:54.037Z,1347989934.037 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-09-18T17:38:54.037Z,1347989934.037 [science_to_67_70:H.SetSpeed] Stopped
2012-09-18T17:38:54.038Z,1347989934.037 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-09-18T17:38:54.038Z,1347989934.038 [science_to_67_70:I.DepthEnvelope] Stopped
2012-09-18T17:38:54.038Z,1347989934.038 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-09-18T17:38:54.038Z,1347989934.038 [science_to_67_70:J.YoYo] Stopped
2012-09-18T17:38:54.038Z,1347989934.038 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-09-18T17:38:54.038Z,1347989934.038 [science_to_67_70:Wpt.Point] Stopped
2012-09-18T17:38:54.170Z,1347989934.171 [WetLabsBB2FL](INFO): Powering down
2012-09-18T17:38:54.642Z,1347989934.642 [MissionManager](IMPORTANT): Started mission Default
2012-09-18T17:38:54.642Z,1347989934.642 [Default] Running Loop=1
2012-09-18T17:38:54.650Z,1347989934.650 [Default](INFO): Aggregate::initialize Default
2012-09-18T17:38:54.650Z,1347989934.650 [Default:D.SetSpeed] Running Loop=1
2012-09-18T17:38:54.650Z,1347989934.651 [Default:D.SetSpeed](DEBUG): Initialize.
2012-09-18T17:38:54.651Z,1347989934.651 [Default:E.GoToSurface] Running Loop=1
2012-09-18T17:38:54.651Z,1347989934.651 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-18T17:38:54.651Z,1347989934.651 [Default:Iridium] Running Loop=1
2012-09-18T17:38:54.651Z,1347989934.651 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-09-18T17:38:54.651Z,1347989934.651 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-18T17:38:54.651Z,1347989934.651 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-09-18T17:38:54.651Z,1347989934.651 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-09-18T17:38:54.651Z,1347989934.651 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-18T17:38:54.653Z,1347989934.653 [Default:CallIridium] Running Loop=1
2012-09-18T17:38:54.653Z,1347989934.653 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-09-18T17:38:54.653Z,1347989934.653 [Default:CallIridium:A] Running Loop=1
2012-09-18T17:38:54.654Z,1347989934.654 [Default:CallIridium:A] Stopped
2012-09-18T17:38:54.654Z,1347989934.654 [Default:CallIridium:B] Running Loop=1
2012-09-18T17:38:54.654Z,1347989934.654 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-09-18T17:38:54.668Z,1347989934.668 [Default:Iridium:B.GoToSurface] Stopped
2012-09-18T17:38:54.668Z,1347989934.668 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-18T17:38:54.669Z,1347989934.668 [Default:Iridium:Read_Iridium] Running Loop=1
2012-09-18T17:38:55.091Z,1347989935.091 [Default:GPS] Running Loop=1
2012-09-18T17:38:55.091Z,1347989935.092 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-09-18T17:38:55.091Z,1347989935.092 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-18T17:38:55.092Z,1347989935.092 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-09-18T17:38:55.092Z,1347989935.092 [Default:GPS:B.GoToSurface] Running Loop=1
2012-09-18T17:38:55.092Z,1347989935.092 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-18T17:38:55.093Z,1347989935.093 [Default:GPS:B.GoToSurface] Stopped
2012-09-18T17:38:55.093Z,1347989935.093 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-18T17:38:55.093Z,1347989935.093 [Default:GPS:Read_GPS] Running Loop=1
2012-09-18T17:38:55.394Z,1347989935.394 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2012-09-18T17:38:55.402Z,1347989935.402 [NAL9602](INFO): Powering up
2012-09-18T17:38:55.424Z,1347989935.424 [Default:GPS:Read_GPS] Stopped
2012-09-18T17:38:55.424Z,1347989935.424 [Default:GPS:D] Running Loop=1
2012-09-18T17:38:55.787Z,1347989935.787 [WetLabsBB2FL](INFO): Powering down
2012-09-18T17:38:55.794Z,1347989935.794 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-09-18T17:38:55.814Z,1347989935.814 [Default:GPS:D] Stopped
2012-09-18T17:38:55.818Z,1347989935.818 [Default:GPS](INFO): Completed Default:GPS
2012-09-18T17:38:55.818Z,1347989935.819 [Default:GPS] Stopped
2012-09-18T17:38:55.819Z,1347989935.819 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-09-18T17:38:55.819Z,1347989935.819 [Default:GPS:A.SetSpeed] Stopped
2012-09-18T17:38:55.819Z,1347989935.819 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-09-18T17:38:56.083Z,1347989936.083 [Turbulence_NPS](INFO): Powering down
2012-09-18T17:38:56.094Z,1347989936.094 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-09-18T17:38:56.291Z,1347989936.291 [CTD_NeilBrown](INFO): Powering down
2012-09-18T17:38:56.302Z,1347989936.302 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2012-09-18T17:39:05.899Z,1347989945.899 [NAL9602](INFO): NAL9602 initialized