Running bin\geopdvc.pl at Tue Jan 25 15:58:41 2011 on host EPHYRA-VM Command line: bin\geopdvc.pl -rov vnta -dive 3485 bin\geopdvc.pl: Getting Expedition object for diveno = 3485 and rname = vnta set_expd(): rov = vnta, diveno = 3485 set_expd(): $self->{EndDDD} = 008 set_expd(): $self->{StartEsecs} = 1262988765 set_expd(): $self->{EndEsecs} = 1262992230 set_expd(): $self->{EndDtg} = 1/8/2010 10:50:30 PM _readLogr(): Reading videologr data from 2010008datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1262988765 & 1262992230 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1262992236, self->{EndEsecs} = 1262992230 _readLogr(): #LOGHOST_SYSTEM_UTC = 345 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1262988775 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1262992226 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1262988775 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1262992226 refineStartAndEndEsecs(): setting StartEsecs to 1262988765 refineStartAndEndEsecs(): setting EndEsecs to 1262989935 refineStartAndEndEsecs(): numMinBack = 2.5, numMinForward = 9.26666666666667 refineStartAndEndEsecs(): Refined StartEsecs = 1262989815, Refined EndEsecs = 1262991586 _readLogr(): Reading videologr data from 2010008datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1262989815 & 1262991586 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1262991596, self->{EndEsecs} = 1262991586 _readLogr(): #LOGHOST_SYSTEM_UTC = 176 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1262989825 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1262991586 bin\geopdvc.pl: expdid = 4250 bin\geopdvc.pl: Calling rovNavCTDO to get raw nav data... rovnavCTDO(): calcUTM = 0 rovnavCTDO(): getRaw = 1 rovNav(): 1, calcUTM = 0 rovNav(): 2, getRaw = 1 rovNav(): calcUTM = 0 rovNav(): DDD = 008 (looping to 008) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010008vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010008vnta.txt esecs, east, north, lat, lon, head, pres 1262990413, 0, 0, 36.868895, -121.897452, 264.5, 42.89 rovNav(): 008: 745 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010008vnta.txt 745 in tList 745 in {self->{rovT}} 745 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1262991015 & 1262990085 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... rovGDCListRefs(): dMedian = 41.8635870667286 rovGDCListRefs(): dStats->sample_range = 42.3196739149186 rovGDCListRefs(): returning (1st elements): 1262989965 36.879290 -122.060617 63.9 2.15393014373266 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 008 (looping to 008) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010008vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010008vntaedited.txt esecs, east, north, lat, lon, head, pres 1262990413, 0, 0, 36.868887, -121.897487, 264.5, 42.89 rovNav(): 008: 745 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010008vntaeframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/008/accesses.txt at bin\geopdvc.pl line 2382 dited.txt rovNav(): Initial # seconds to fill at beginning of dive = 150 starting at StartEsecs = 1262989815 rovNav(): Initial # seconds to fill at end of dive = 740 ending at EndEsecs =1262991586 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1262989825 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1262991586 rovNav(): numMinBack = 2.33333333333333, numMinForward = 9.26666666666667 rovNav(): Refined StartEsecs = 1262989815, Refined EndEsecs = 1262991586 rovNav(): Refined # seconds to fill at beginning of dive = 150 starting at StartEsecs = 1262989815 rovNav(): Refined # seconds to fill at end of dive = 740 ending at EndEsecs =1262991586 rovNav(): num tList = 745 rovNav(): num eList = 745 rovNav(): Adding data to begining of dive from logr indices 0 .. 14 rovNav(): Adding data to end of dive from logr indices 103 .. 176 rovNav(): num in tList now = 834 rovNav(): num in eList now = 834 834 in tList 834 in {self->{rovT}} 834 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1262991015 & 1262990085 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXX.......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X rovGDCListRefs(): dMedian = 40.5933158320529 rovGDCListRefs(): dStats->sample_range = 49.3147913909858 rovGDCListRefs(): returning (1st elements): 1262989825 36.868360 -121.896782 332.3 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.86887800 hash{lon} = -121.89763000 latMin = 30.868878 latMax = 42.868878 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.868878 AND 42.868878 AND Longitude BETWEEN -127.89763 AND -115.89763 Dive lat = 36.86887800, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.89763000 & lat = 36.86887800 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3485rov_orig.wrl Making color lookup world... var = Light, vmin = 40, vmax = 100 val = 40, color = .8 .7 0 val = 70, color = .6 .5 0 val = 85, color = .1 .1 0 val = 100, color = 1 1 1 var = O2, vmin = 0.2, vmax = 5 val = 0.2, color = 1 1 1 val = 0.3, color = 1 1 .2 val = 0.5, color = 1 1 0 val = 0.7, color = 0 1 0 val = 5, color = 0 0 1 var = Sal, vmin = 33, vmax = 35 val = 33, color = 1 1 .1 val = 33.5, color = 1 1 0 val = 34, color = 1 0 0 val = 34.5, color = .2 1 0 val = 35, color = 0 1 0 var = Temp, vmin = 4, vmax = 15 val = 4, color = 0 0 1 val = 8, color = 1 1 0 val = 10, color = 0 1 0 val = 15, color = 1 0 0 World = geo2010/vnta/vnta3485lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 176 shipNav(): $#{$self->{shipLat}}[last] = 36.863065 Position subsampling set to 1 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.4467 0.3773 0.0000 setROVcolorlines(): Assigning color line for O2... varColors = O2Colors index0 = 0 0 1 setROVcolorlines(): Assigning color line for Sal... varColors = SalColors index0 = 1.0000 1.0000 0.0930 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.6310 0.3690 0.0000 makeTracks(): n = 835 makeTracks(): Looping through 176 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3485tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2010/vnta/vnta3485mergedCtdNav.txt... bin\geopdvc.pl: Annotations: annotations(): Executing sql: SELECT DISTINCT DateDiff(ss, '01/01/70', RecordedDate) AS Esecs, CONVERT(CHAR(8), RecordedDate, 1) + ' ' + SUBSTRING(CONVERT(CHAR(19), RecordedDate, 120), 12, 8) AS DateTime, VideoArchiveName AS TapeNum, TapeTimeCode, ConceptName, Image AS StillImageURL, Depth, Latitude, Longitude, CameraDirection AS Direction, ISNULL(Observer, '') AS Observer, AnnotationMode, ObservationID_FK AS ObservationID FROM dbo.Annotations WHERE (ShipName = 'Point Lobos') AND (RecordedDate BETWEEN '1/8/2010 10:32:45 PM' AND '1/8/2010 10:50:30 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/8/2010 10:32:45 PM' AND '1/8/2010 10:50:30 PM') _findIndex(): Looking for epoch secs 1262990011 ... *** Warning: Position time is -46 seconds away from search time. *** >>> p = 15: annotations(): 15, 1262990011, 01/08/10 22:33:31, V3485-01HD, 04:03:09:28, Chrysaora fuscescens, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_03_09_28.jpg, , descend, MARS, o, 20040139 _findIndex(): Looking for epoch secs 1262990133 ... Found time 1262990137 after 3 iterations. >>> p = 52: annotations(): 52, 1262990133, 01/08/10 22:35:33, V3485-01HD, 04:05:11:12, Chrysaora fuscescens, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_05_11_12.jpg, 6.84999990463257, descend, linda, o, 20040140 _findIndex(): Looking for epoch secs 1262990638 ... Found time 1262990633 after 4 iterations. >>> p = 547: annotations(): 547, 1262990638, 01/08/10 22:43:58, V3485-01HD, 04:13:36:19, sand, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_13_36_19.jpg, 40.2099990844727, stationary, MARS, o, 20040141 _findIndex(): Looking for epoch secs 1262990704 ... Found time 1262990704 after 6 iterations. >>> p = 618: annotations(): 618, 1262990704, 01/08/10 22:45:04, V3485-01HD, 04:14:42:11, bubbles, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_14_42_11.jpg, 32.4599990844727, ascend, linda, o, 20040142 _findIndex(): Looking for epoch secs 1262990773 ... Found time 1262990776 after 5 iterations. >>> p = 690: annotations(): 690, 1262990773, 01/08/10 22:46:13, V3485-01HD, 04:15:51:04, Chrysaora fuscescens, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_15_51_04.jpg, 5.51999998092651, ascend, MARS, o, 20040143 _findIndex(): Looking for epoch secs 1262990790 ... Found time 1262990789 after 4 iterations. >>> p = 703: annotations(): 703, 1262990790, 01/08/10 22:46:30, V3485-01HD, 04:16:08:20, Chrysaora fuscescens, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_16_08_20.jpg, 5.36999988555908, ascend, linda, o, 20040144 _findIndex(): Looking for epoch secs 1262990797 ... Found time 1262990802 after 5 iterations. >>> p = 716: annotations(): 716, 1262990797, 01/08/10 22:46:37, V3485-01HD, 04:16:15:04, Chrysaora fuscescens, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_16_15_04.jpg, 5.1399998664856, ascend, linda, o, 20040145 _findIndex(): Looking for epoch secs 1262990815 ... Found time 1262990815 after 2 iterations. >>> p = 729: annotations(): 729, 1262990815, 01/08/10 22:46:55, V3485-01HD, 04:16:33:19, Chrysaora fuscescens, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_16_33_19.jpg, 4.30000019073486, ascend, linda, o, 20040146 _findIndex(): Looking for epoch secs 1262990829 ... Found time 1262990828 after 5 iterations. >>> p = 742: annotations(): 742, 1262990829, 01/08/10 22:47:09, V3485-01HD, 04:16:47:10, Chrysaora fuscescens, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3485/04_16_47_10.jpg, 3.78999996185303, ascend, linda, o, 20040147 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta3485anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/008/accesses.txt at bin\geopdvc.pl line 625 Annotations(): Placing annotation 2: sand Ancillary data = ["sand", "P: 7.8 db", "T: 12.93 C", "S: 33.318", "O: 5.504 ml/l", "L: 81.46 %"] placeAnnotations(): Placing annotation 4: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 37.5 db", "T: 12.5 C", "S: 33.34", "O: 5.098 ml/l", "L: 76.81 %"] placeAnnotations(): Placing annotation 6: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 7.4 db", "T: 12.864 C", "S: 33.32", "O: 5.395 ml/l", "L: 80.33 %"] placeAnnotations(): Placing annotation 8: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 6.1 db", "T: 12.886 C", "S: 33.319", "O: 5.443 ml/l", "L: 80.34 %"] inline_name = vnta3485anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 45.5 db", "T: 12.484 C", "S: 33.34", "O: 5.083 ml/l", "L: 63.05 %"] placeAnnotations(): Placing annotation 2: sand Ancillary data = ["sand", "P: 7.8 db", "T: 12.93 C", "S: 33.318", "O: 5.504 ml/l", "L: 81.46 %"] placeAnnotations(): Placing annotation 3: bubbles Ancillary data = ["bubbles", "P: 42.5 db", "T: 12.507 C", "S: 33.34", "O: 5.089 ml/l", "L: 78.11 %"] placeAnnotations(): Placing annotation 4: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 37.5 db", "T: 12.5 C", "S: 33.34", "O: 5.098 ml/l", "L: 76.81 %"] placeAnnotations(): Placing annotation 5: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 7.5 db", "T: 12.824 C", "S: 33.324", "O: 5.367 ml/l", "L: 80.26 %"] placeAnnotations(): Placing annotation 6: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 7.4 db", "T: 12.864 C", "S: 33.32", "O: 5.395 ml/l", "L: 80.33 %"] placeAnnotations(): Placing annotation 7: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 6.9 db", "T: 12.878 C", "S: 33.321", "O: 5.419 ml/l", "L: 80.34 %"] placeAnnotations(): Placing annotation 8: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 6.1 db", "T: 12.886 C", "S: 33.319", "O: 5.443 ml/l", "L: 80.34 %"] placeAnnotations(): Placing annotation 9: Ancillary data = ["", "P: 5.5 db", "T: 12.903 C", "S: 33.321", "O: 5.46 ml/l", "L: 80.39 %"] inline_name = vnta3485anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3485... frameGrabs(): matched 04_03_09_28 in