Running bin\geopdvc.pl at Tue Jan 25 16:13:04 2011 on host EPHYRA-VM Command line: bin\geopdvc.pl -rov vnta -dive 3498 bin\geopdvc.pl: Getting Expedition object for diveno = 3498 and rname = vnta set_expd(): rov = vnta, diveno = 3498 set_expd(): $self->{EndDDD} = 026 set_expd(): $self->{StartEsecs} = 1264521990 set_expd(): $self->{EndEsecs} = 1264524945 set_expd(): $self->{EndDtg} = 1/26/2010 4:35:45 PM _readLogr(): Reading videologr data from 2010026datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1264521990 & 1264524945 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1264524947, self->{EndEsecs} = 1264524945 _readLogr(): #LOGHOST_SYSTEM_UTC = 294 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1264521997 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1264524937 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1264521997 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1264524937 refineStartAndEndEsecs(): setting StartEsecs to 1264521990 refineStartAndEndEsecs(): setting EndEsecs to 1264522677 refineStartAndEndEsecs(): numMinBack = 10.55, numMinForward = 12.5333333333333 refineStartAndEndEsecs(): Refined StartEsecs = 1264522557, Refined EndEsecs = 1264524497 _readLogr(): Reading videologr data from 2010026datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1264522557 & 1264524497 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1264524507, self->{EndEsecs} = 1264524497 _readLogr(): #LOGHOST_SYSTEM_UTC = 193 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1264522567 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1264524497 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 1264523326, 0, 0, 36.822455, -121.943610, 271.2, 88.38 rovNav(): 026: 919 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010026vnta.txt 919 in tList 919 in {self->{rovT}} 919 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1264523325 & 1264523655 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 53.6134424621299 rovGDCListRefs(): dStats->sample_range = 85.8711094108945 rovGDCListRefs(): returning (1st elements): 1264522826 36.793602 -121.847227 167.6 2.024908858155 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 1264523326, 0, 0, 36.822453, -121.943602, 271.2, 88.38 rovNav(): 026: 919 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010026vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 269 starting at StartEsecs = 1264522557 rovNav(): Initial # seconds to fill at end of dive = 750 ending at EndEsecs =126frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/026/accesses.txt at bin\geopdvc.pl line 2382 4524497 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1264522567 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1264524497 rovNav(): numMinBack = 10.3833333333333, numMinForward = 12.5333333333333 rovNav(): Refined StartEsecs = 1264522557, Refined EndEsecs = 1264524497 rovNav(): Refined # seconds to fill at beginning of dive = 269 starting at StartEsecs = 1264522557 rovNav(): Refined # seconds to fill at end of dive = 750 ending at EndEsecs =1264524497 rovNav(): num tList = 919 rovNav(): num eList = 919 rovNav(): Adding data to begining of dive from logr indices 0 .. 25 rovNav(): Adding data to end of dive from logr indices 118 .. 193 rovNav(): num in tList now = 1021 rovNav(): num in eList now = 1021 1021 in tList 1021 in {self->{rovT}} 1021 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1264523325 & 1264523655 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXX................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 50.8447858105276 rovGDCListRefs(): dStats->sample_range = 92.8860875408453 rovGDCListRefs(): returning (1st elements): 1264522567 36.821728 -121.943593 55.2 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.82229800 hash{lon} = -121.94351800 latMin = 30.822298 latMax = 42.822298 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.822298 AND 42.822298 AND Longitude BETWEEN -127.943518 AND -115.943518 Dive lat = 36.82229800, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.94351800 & lat = 36.82229800 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3498rov_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/vnta3498lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 193 shipNav(): $#{$self->{shipLat}}[last] = 36.822405 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 = 1022 makeTracks(): Looping through 193 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3498tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2010/vnta/vnta3498mergedCtdNav.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 4:26:30 PM' AND '1/26/2010 4:35:45 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/26/2010 4:26:30 PM' AND '1/26/2010 4:35:45 PM') _findIndex(): Looking for epoch secs 1264523190 ... Found time 1264523191 after 6 iterations. >>> p = 390: annotations(): 390, 1264523190, 01/26/10 16:26:30, V3498-01HD, 00:04:41:15, benthos, , 85.9199981689453, descend, linda, o, 3177543 _findIndex(): Looking for epoch secs 1264523190 ... Found time 1264523191 after 6 iterations. >>> p = 390: annotations(): 390, 1264523190, 01/26/10 16:26:30, V3498-01HD, 00:04:41:15, mud, , 85.9199981689453, descend, linda, o, 3177544 _findIndex(): Looking for epoch secs 1264523190 ... Found time 1264523191 after 6 iterations. >>> p = 390: annotations(): 390, 1264523190, 01/26/10 16:26:30, V3498-01HD, 00:04:57:04, water, , 85.9199981689453, cruise, linda, o, 3178267 _findIndex(): Looking for epoch secs 1264523190 ... Found time 1264523191 after 6 iterations. >>> p = 390: annotations(): 390, 1264523190, 01/26/10 16:26:30, V3498-01HD, 00:05:06:12, water, , 85.9199981689453, cruise, linda, o, 3178276 _findIndex(): Looking for epoch secs 1264523190 ... Found time 1264523191 after 6 iterations. >>> p = 390: annotations(): 390, 1264523190, 01/26/10 16:26:30, V3498-01HD, 00:05:09:24, Nanomia bijuga, , 85.9199981689453, cruise, linda, o, 3178277 _findIndex(): Looking for epoch secs 1264523258 ... Found time 1264523263 after 5 iterations. >>> p = 462: annotations(): 462, 1264523258, 01/26/10 16:27:38, V3498-01HD, 00:06:27:23, water, , 87.7099990844727, cruise, linda, o, 3178296 _findIndex(): Looking for epoch secs 1264523259 ... Found time 1264523263 after 5 iterations. >>> p = 462: annotations(): 462, 1264523259, 01/26/10 16:27:39, V3498-01HD, 00:06:28:11, water, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3498/00_06_28_11.jpg, 87.4599990844727, stationary, lonny, o, 20040495 _findIndex(): Looking for epoch secs 1264523413 ... Found time 1264523414 after 6 iterations. >>> p = 613: annotations(): 613, 1264523413, 01/26/10 16:30:13, V3498-01HD, 00:09:02:19, water, , 84.6800003051758, ascend, linda, o, 3178307 _findIndex(): Looking for epoch secs 1264523433 ... Found time 1264523438 after 2 iterations. >>> p = 637: annotations(): 637, 1264523433, 01/26/10 16:30:33, V3498-01HD, 00:09:22:27, ROV, , 79.5400009155273, ascend, lonny, o, 20040496 _findIndex(): Looking for epoch secs 1264523450 ... Found time 1264523454 after 5 iterations. >>> p = 653: annotations(): 653, 1264523450, 01/26/10 16:30:50, V3498-01HD, 00:09:39:14, water, , 74.4000015258789, ascend, linda, o, 3178312 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 71.5 db", "T: 13.078 C", "S: 33.281", "O: 5.22 ml/l", "L: 80.48 %"] inline_name = vnta3498anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 80.4 db", "T: 13.072 C", "S: 33.288", "O: 5.197 ml/l", "L: 79.92 %"] placeAnnotations(): Placing annotation 4: Nanomia bijuga Ancillary data = ["Nanomia bijuga", "P: 80.4 db", "T: 13.072 C", "S: 33.288", "O: 5.197 ml/l", "L: 79.92 %"] placeAnnotations(): Placing annotation 6: water Ancillary data = ["water", "P: 87.6 db", "T: 12.954 C", "S: 33.316", "O: 5.13 ml/l", "L: 68.96 %"] placeAnnotations(): Placing annotation 8: ROV Ancillary data = ["ROV", "P: 83.9 db", "T: 13.072 C", "S: 33.292", "O: 5.18 ml/l", "L: 81.14 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 71.5 db", "T: 13.078 C", "S: 33.281", "O: 5.22 ml/l", "L: 80.48 %"] inline_name = vnta3498anno02.wrl bin\frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/026/accesses.txt at bin\geopdvc.pl line 625 geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: mud Ancillary data = ["mud", "P: 80.4 db", "T: 13.072 C", "S: 33.288", "O: 5.197 ml/l", "L: 79.92 %"] placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 80.4 db", "T: 13.072 C", "S: 33.288", "O: 5.197 ml/l", "L: 79.92 %"] placeAnnotations(): Placing annotation 3: water Ancillary data = ["water", "P: 80.4 db", "T: 13.072 C", "S: 33.288", "O: 5.197 ml/l", "L: 79.92 %"] placeAnnotations(): Placing annotation 4: Nanomia bijuga Ancillary data = ["Nanomia bijuga", "P: 80.4 db", "T: 13.072 C", "S: 33.288", "O: 5.197 ml/l", "L: 79.92 %"] placeAnnotations(): Placing annotation 5: water Ancillary data = ["water", "P: 80.4 db", "T: 13.072 C", "S: 33.288", "O: 5.197 ml/l", "L: 79.92 %"] placeAnnotations(): Placing annotation 6: water Ancillary data = ["water", "P: 87.6 db", "T: 12.954 C", "S: 33.316", "O: 5.13 ml/l", "L: 68.96 %"] placeAnnotations(): Placing annotation 7: water Ancillary data = ["water", "P: 87.6 db", "T: 12.954 C", "S: 33.316", "O: 5.13 ml/l", "L: 68.96 %"] placeAnnotations(): Placing annotation 8: ROV Ancillary data = ["ROV", "P: 83.9 db", "T: 13.072 C", "S: 33.292", "O: 5.18 ml/l", "L: 81.14 %"] placeAnnotations(): Placing annotation 9: water Ancillary data = ["water", "P: 75.6 db", "T: 13.078 C", "S: 33.284", "O: 5.209 ml/l", "L: 80.56 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 71.5 db", "T: 13.078 C", "S: 33.281", "O: 5.22 ml/l", "L: 80.48 %"] inline_name = vnta3498anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3498... frameGrabs(): matched 00_06_28_11 in [IMG]00_06_28_11.jpg19-Feb-2010 00:10 45K iList = 00_06_28_11.jpg _findIndex(): Looking for epoch secs 1264523259 ... Found time 1264523263 after 5 iterations. >>> p = 462: frameGrabs(): index = 462 EXPD::frameGrabs(): 462: Time=1264523265, Depth=86.94, Lat=36.822372, Lon=-121.943497, Pres=87.6, Temp=12.954, Sal=33.316, O2=5.13, Light=68.96 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_06_28_11.jpg 1264523259 fw= 0.0 cm XplaceImages(): indx = 462, geoPosition = 36.822372, -121.943497, -87.4792948629892, rovDepth = 87.4792948629892 bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_06_28_11.jpg 1264523259 fw= 0.0 cm XplaceImages(): indx = 462, geoPosition = 36.822372, -121.943497, -87.4792948629892, rovDepth = 87.4792948629892 bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_06_28_11.jpg 1264523259 fw= 0.0 cm XplaceImages(): indx = 462, geoPosition = 36.822372, -121.943497, -87.4792948629892, rovDepth = 87.4792948629892 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 4:26:30 PM' AND '1/26/2010 4:35:45 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2010/vnta/vnta3498.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4264,'/3Dreplay/3D.asp?dives=vnta3498','vnta3498','','','',3498 Executing sql: UPDATE Dive SET DiveLatMid = 36.8223, DiveLonMid = -121.9435, DiveDepthMid = -50.8 WHERE RovName = 'vnta' AND DiveNumber = 3498