Running bin\geopdvc.pl at Tue Jan 25 16:13:21 2011 on host EPHYRA-VM Command line: bin\geopdvc.pl -rov vnta -dive 3499 bin\geopdvc.pl: Getting Expedition object for diveno = 3499 and rname = vnta set_expd(): rov = vnta, diveno = 3499 set_expd(): $self->{EndDDD} = 026 set_expd(): $self->{StartEsecs} = 1264537140 set_expd(): $self->{EndEsecs} = 1264540275 set_expd(): $self->{EndDtg} = 1/26/2010 8:51:15 PM _readLogr(): Reading videologr data from 2010026datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1264537140 & 1264540275 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1264540280, self->{EndEsecs} = 1264540275 _readLogr(): #LOGHOST_SYSTEM_UTC = 312 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1264537150 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1264540270 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1264537150 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1264540270 refineStartAndEndEsecs(): setting StartEsecs to 1264537140 refineStartAndEndEsecs(): setting EndEsecs to 1264537250 refineStartAndEndEsecs(): numMinBack = 20.1666666666667, numMinForward = 17.0833333333333 refineStartAndEndEsecs(): Refined StartEsecs = 1264537130, Refined EndEsecs = 1264540100 _readLogr(): Reading videologr data from 2010026datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1264537130 & 1264540100 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1264540110, self->{EndEsecs} = 1264540100 _readLogr(): #LOGHOST_SYSTEM_UTC = 296 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1264537140 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1264540100 bin\geopdvc.pl: expdid = 4264 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 = 026 (looping to 026) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010026vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010026vnta.txt esecs, east, north, lat, lon, head, pres 1264538438, 0, 0, 36.822313, -121.943537, 98.1, 39.40 1264538939, 0, 0, 36.713932, -121.907638, 99.4, 51.84 rovNav(): 026: 723 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010026vnta.txt 723 in tList 723 in {self->{rovT}} 723 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1264539045 & 1264538640 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 81.1544509346588 rovGDCListRefs(): dStats->sample_range = 86.1686912956833 rovGDCListRefs(): returning (1st elements): 1264538187 36.822313 -121.943537 317.3 2.18371883684537 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 026 (looping to 026) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010026vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010026vntaedited.txt esecs, east, north, lat, lon, head, pres 1264538438, 0, 0, 36.715532, -121.908451, 98.1, 39.40 1264538939, 0, 0, 36.713890, -121.9frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/026/accesses.txt at bin\geopdvc.pl line 2382 07628, 99.4, 51.84 rovNav(): 026: 723 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010026vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 1057 starting at StartEsecs = 1264537130 rovNav(): Initial # seconds to fill at end of dive = 1017 ending at EndEsecs =1264540100 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1264537140 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1264540100 rovNav(): numMinBack = 20, numMinForward = 17.0833333333333 rovNav(): Refined StartEsecs = 1264537130, Refined EndEsecs = 1264540100 rovNav(): Refined # seconds to fill at beginning of dive = 1057 starting at StartEsecs = 1264537130 rovNav(): Refined # seconds to fill at end of dive = 1017 ending at EndEsecs =1264540100 rovNav(): num tList = 723 rovNav(): num eList = 723 rovNav(): Adding data to begining of dive from logr indices 0 .. 104 rovNav(): Adding data to end of dive from logr indices 195 .. 296 rovNav(): num in tList now = 930 rovNav(): num in eList now = 930 930 in tList 930 in {self->{rovT}} 930 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1264539045 & 1264538640 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXX.....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 53.7628020328209 rovGDCListRefs(): dStats->sample_range = 93.3532538268945 rovGDCListRefs(): returning (1st elements): 1264537140 36.719565 -121.912062 73.0 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.71394500 hash{lon} = -121.90774400 latMin = 30.713945 latMax = 42.713945 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.713945 AND 42.713945 AND Longitude BETWEEN -127.907744 AND -115.907744 Dive lat = 36.71394500, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.90774400 & lat = 36.71394500 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3499rov_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/vnta3499lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 296 shipNav(): $#{$self->{shipLat}}[last] = 36.727220 Position subsampling set to 1 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = .8 .7 0 setROVcolorlines(): Assigning color line for O2... varColors = O2Colors index0 = 1 1 1 setROVcolorlines(): Assigning color line for Sal... varColors = SalColors index0 = 1 1 .1 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0 0 1 makeTracks(): n = 931 makeTracks(): Looping through 296 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3499tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2010/vnta/vnta3499mergedCtdNav.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/26/2010 8:39:00 PM' AND '1/26/2010 8:51:15 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/26/2010 8:39:00 PM' AND '1/26/2010 8:51:15 PM') _findIndex(): Looking for epoch secs 1264538340 ... *** Warning: Position time is -153 seconds away from search time. *** >>> p = 105: annotations(): 105, 1264538340, 01/26/10 20:39:00, V3499-01HD, 00:11:25:09, water, , , descend, linda, o, 3178325 _findIndex(): Looking for epoch secs 1264538340 ... *** Warning: Position time is -153 seconds away from search time. *** >>> p = 105: annotations(): 105, 1264538340, 01/26/10 20:39:00, V3499-01HD, 00:11:53:00, water, , , descend, linda, o, 3178330 _findIndex(): Looking for epoch secs 1264538355 ... Found time 1264538360 after 8 iterations. >>> p = 106: annotations(): 106, 1264538355, 01/26/10 20:39:15, V3499-01HD, 00:12:23:08, water, , 2.25999999046326, descend, linda, o, 3178333 _findIndex(): Looking for epoch secs 1264538393 ... Found time 1264538391 after 6 iterations. >>> p = 137: annotations(): 137, 1264538393, 01/26/10 20:39:53, V3499-01HD, 00:13:02:00, Chrysaora fuscescens, , 16.2700004577637, descend, linda, o, 3178336 _findIndex(): Looking for epoch secs 1264538427 ... Found time 1264538428 after 3 iterations. >>> p = 174: annotations(): 174, 1264538427, 01/26/10 20:40:27, V3499-01HD, 00:13:35:13, Aurelia aurita, , 33.0499992370605, descend, linda, o, 3178345 _findIndex(): Looking for epoch secs 1264538521 ... Found time 1264538522 after 6 iterations. >>> p = 268: annotations(): 268, 1264538521, 01/26/10 20:42:01, V3499-01HD, 00:15:09:11, water, , 83.5899963378906, descend, linda, o, 3178358 _findIndex(): Looking for epoch secs 1264538531 ... Found time 1264538529 after 5 iterations. >>> p = 275: annotations(): 275, 1264538531, 01/26/10 20:42:11, V3499-01HD, 00:15:19:22, benthos, , 86.3499984741211, descend, linda, o, 3178362 _findIndex(): Looking for epoch secs 1264538562 ... Found time 1264538558 after 5 iterations. >>> p = 304: annotations(): 304, 1264538562, 01/26/10 20:42:42, V3499-01HD, 00:15:49:12, water, , 86.5100021362305, cruise, linda, o, 3178367 _findIndex(): Looking for epoch secs 1264538578 ... Found time 1264538573 after 4 iterations. >>> p = 319: annotations(): 319, 1264538578, 01/26/10 20:42:58, V3499-01HD, 00:16:05:17, water, , 87.8499984741211, cruise, linda, o, 3178370 _findIndex(): Looking for epoch secs 1264538585 ... Found time 1264538587 after 5 iterations. >>> p = 333: annotations(): 333, 1264538585, 01/26/10 20:43:05, V3499-01HD, 00:16:11:19, sediment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3499/00_16_11_19.jpg, 88.129997253418, cruise, lonny, o, 20040498 _findIndex(): Looking for epoch secs 1264538598 ... Found time 1264538602 after 2 iterations. >>> p = 348: annotations(): 348, 1264538598, 01/26/10 20:43:18, V3499-01HD, 00:16:26:04, water, , 88.2300033569336, cruise, linda, o, 3178371 _findIndex(): Looking for epoch secs 1264538612 ... Found time 1264538616 after 5 iterations. >>> p = 362: annotations(): 362, 1264538612, 01/26/10 20:43:32, V3499-01HD, 00:16:39:23, water, , 88.1600036621094, cruise, linda, o, 3178372 _findIndex(): Looking for epoch secs 1264538634 ... Found time 1264538631 after 4 iterations. >>> p = 377: annotations(): 377, 1264538634, 01/26/10 20:43:54, V3499-01HD, 00:17:02:01, water, , 87.8399963378906, cruise, linda, o, 3178373 _findIndex(): Looking for epoch secs 1264538643 ... Found time 1264538645 after 5 iterations. >>> p = 391: annotations(): 391, 1264538643, 01/26/10 20:44:03, V3499-01HD, 00:17:10:27, water, , 87.9899978637695, cruise, linda, o, 3178375 _findIndex(): Looking for epoch secs 1264538700 ... Found time 1264538704 after 5 iterations. >>> p = 450: annotations(): 450, 1264538700, 01/26/10 20:45:00, V3499-01HD, 00:18:08:03, water, , 87.8399963378906, cruise, linda, o, 3178377 _findIndex(): Looking for epoch secs 1264538734 ... Found time 1264538733 after 5 iterations. >>> p = 479: annotations(): 479, 1264538734, 01/26/10 20:45:34, V3499-01HD, 00:18:41:23, water, , 84.8499984741211, ascend, linda, o, 3178378 _findIndex(): Looking for epoch secs 1264538776 ... Found time 1264538777 after 3 iterations. >>> p = 523: annotations(): 523, 1264538776, 01/26/10 20:46:16, V3499-01HD, 00:19:23:25, sediment, , 84.5, ascend, linda, o, 3178382 _findIndex(): Looking for epoch secs 1264538820 ... Found time 1264538820 after 5 iterations. >>> p = 566: annotations(): 566, 1264538820, 01/26/10 20:47:00, V3499-01HD, 00:20:07:09, ROV, , 87.629997253418, cruise, lonny, o, 20040499 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: water Ancillary data = ["water", "P: 87.1 db", "T: 12.91 C", "S: 33.34", "O: 5.061 ml/l", "L: 74.95 %"] inline_name = vnta3499anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 49.9 db", "T: 13.11 C", "S: 33.276", "O: 5.328 ml/l", "L: 81.71 %"] placeAnnotations(): Placing annotation 4: Aurelia aurita Ancillary data = ["Aurelia aurita", "P: 11.6 db", "T: 13.123 C", "S: 33.232", "O: 5.449 ml/l", "L: 80.12 %"] placeAnnotations(): Placing annotation 6: benthos Ancillary data = ["benthos", "P: 82.1 db", "T: 13.11 C", "S: 33.302", "O: 5.211 ml/l", "L: 82.12 %"] placeAnnotations(): Placing annotation 8: water Ancillary data = ["water", "P: 86.2 db", "T: 12.986 C", "S: 33.325", "O: 5.145 ml/l", "L: 75.37 %"] placeAnnotations(): Placing annotation 10: water Ancillary data = ["water", "P: 87.1 db", "T: 12.91 C", "S: 33.34", "O: 5.061 ml/l", "L: 74.95 %"] placeAnnotations(): Placing annotation 12: water Ancillary data = ["water", "P: 87 db", "T: 12.881 C", "S: 33.344", "O: 4.911 ml/l", "L: 65.56 %"] placeAnnotations(): Placing annotation 14: water Ancillary data = ["water", "P: 86.9 db", "T: 12.87 C", "S: 33.346", "O: 4.846 ml/l", "L: 43.02 %"] placeAnnotations(): Placing annotation 16: sediment Ancillary data = ["sediment", "P: 84 db", "T: 13.056 C", "S: 33.322", "O: 4.917 ml/l", "L: 77.05 %"] placeAnnotations(): Placing annotation 18: Ancillary data = ["", "P: 86.6 db", "T: 12.917 C", "S: 33.339", "O: 5.032 ml/l", "L: 62.74 %"] inline_name = vnta3499anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: water Ancillary data = ["water", "P: 49.9 db", "T: 13.11 C", "S: 33.276", "O: 5.328 ml/l", "L: 81.71 %"] placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 49.9 db", "T: 13.11 C", "S: 33.276", "O: 5.328 ml/l", "L: 81.71 %"] placeAnnotations(): Placing annotation 3: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 49.9 db", "T: 13.11 C", "S: 33.276", "O: 5.328 ml/l", "L: 81.71 %"] placeAnnotations(): Placing annotation 4: Aurelia aurita Ancillary data = ["Aurelia aurita", "P: 11.6 db", "T: 13.123 C", "S: 33.232", "O: 5.449 ml/l", "L: 80.12 %"] placeAnnotations(): Placing annotation 5: water Ancillary data = ["water", "P: 33.7 db", "T: 13.101 C", "S: 33.264", "O: 5.374 ml/l", "L: 81.43 %"] placeAnnotations(): Placing annotation 6: benthos Ancillary data = ["benthos", "P: 82.1 db", "T: 13.11 C", "S: 33.302", "O: 5.211 ml/l", "L: 82.12 %"] placeAnnotations(): Placing annotatiframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/026/accesses.txt at bin\geopdvc.pl line 625 on 7: water Ancillary data = ["water", "P: 85.2 db", "T: 13.085 C", "S: 33.311", "O: 5.186 ml/l", "L: 80.45 %"] placeAnnotations(): Placing annotation 8: water Ancillary data = ["water", "P: 86.2 db", "T: 12.986 C", "S: 33.325", "O: 5.145 ml/l", "L: 75.37 %"] placeAnnotations(): Placing annotation 9: sediment Ancillary data = ["sediment", "P: 87.1 db", "T: 12.91 C", "S: 33.34", "O: 5.061 ml/l", "L: 74.95 %"] placeAnnotations(): Placing annotation 10: water Ancillary data = ["water", "P: 87.1 db", "T: 12.91 C", "S: 33.34", "O: 5.061 ml/l", "L: 74.95 %"] placeAnnotations(): Placing annotation 11: water Ancillary data = ["water", "P: 87 db", "T: 12.896 C", "S: 33.342", "O: 4.978 ml/l", "L: 72.98 %"] placeAnnotations(): Placing annotation 12: water Ancillary data = ["water", "P: 87 db", "T: 12.881 C", "S: 33.344", "O: 4.911 ml/l", "L: 65.56 %"] placeAnnotations(): Placing annotation 13: water Ancillary data = ["water", "P: 87 db", "T: 12.862 C", "S: 33.347", "O: 4.873 ml/l", "L: 46.38 %"] placeAnnotations(): Placing annotation 14: water Ancillary data = ["water", "P: 86.9 db", "T: 12.87 C", "S: 33.346", "O: 4.846 ml/l", "L: 43.02 %"] placeAnnotations(): Placing annotation 15: water Ancillary data = ["water", "P: 86.8 db", "T: 12.893 C", "S: 33.342", "O: 4.808 ml/l", "L: 57.37 %"] placeAnnotations(): Placing annotation 16: sediment Ancillary data = ["sediment", "P: 84 db", "T: 13.056 C", "S: 33.322", "O: 4.917 ml/l", "L: 77.05 %"] placeAnnotations(): Placing annotation 17: ROV Ancillary data = ["ROV", "P: 83.1 db", "T: 13.065 C", "S: 33.314", "O: 5.08 ml/l", "L: 78.49 %"] placeAnnotations(): Placing annotation 18: Ancillary data = ["", "P: 86.6 db", "T: 12.917 C", "S: 33.339", "O: 5.032 ml/l", "L: 62.74 %"] inline_name = vnta3499anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3499... frameGrabs(): matched 00_16_11_19 in [IMG]00_16_11_19.jpg18-Feb-2010 00:12 45K iList = 00_16_11_19.jpg _findIndex(): Looking for epoch secs 1264538585 ... Found time 1264538587 after 5 iterations. >>> p = 333: frameGrabs(): index = 333 EXPD::frameGrabs(): 333: Time=1264538580, Depth=86.44, Lat=36.714449, Lon=-121.908095, Pres=87.1, Temp=12.91, Sal=33.34, O2=5.061, Light=74.95 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 1 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_16_11_19.jpg 1264538585 fw= 0.0 cm XplaceImages(): indx = 333, geoPosition = 36.714449, -121.908095, -87.8670828766967, rovDepth = 87.8670828766967 bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_16_11_19.jpg 1264538585 fw= 0.0 cm XplaceImages(): indx = 333, geoPosition = 36.714449, -121.908095, -87.8670828766967, rovDepth = 87.8670828766967 bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_16_11_19.jpg 1264538585 fw= 0.0 cm XplaceImages(): indx = 333, geoPosition = 36.714449, -121.908095, -87.8670828766967, rovDepth = 87.8670828766967 bin\geopdvc.pl: Samples: samples(): Executing sql: SELECT DateDiff(ss, '01/01/70', CollectionEventDtg) AS Esecs, CollectionVIMSConcept, FrameGrabImageURL, SampleID, CollectionRefName FROM vwCollSamplePerson WHERE (CollectionEventDTG BETWEEN '1/26/2010 8:39:00 PM' AND '1/26/2010 8:51:15 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2010/vnta/vnta3499.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4264,'/3Dreplay/3D.asp?dives=vnta3499','vnta3499','','','',3499 Executing sql: UPDATE Dive SET DiveLatMid = 36.7139, DiveLonMid = -121.9077, DiveDepthMid = -53.8 WHERE RovName = 'vnta' AND DiveNumber = 3499