Running bin\geopdvc.pl at Tue Jan 25 15:59:01 2011 on host EPHYRA-VM Command line: bin\geopdvc.pl -rov vnta -dive 3486 bin\geopdvc.pl: Getting Expedition object for diveno = 3486 and rname = vnta set_expd(): rov = vnta, diveno = 3486 set_expd(): $self->{EndDDD} = 012 set_expd(): $self->{StartEsecs} = 1263318161 set_expd(): $self->{EndEsecs} = 1263321293 set_expd(): $self->{EndDtg} = 1/12/2010 6:14:53 PM _readLogr(): Reading videologr data from 2010012datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1263318161 & 1263321293 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1263321302, self->{EndEsecs} = 1263321293 _readLogr(): #LOGHOST_SYSTEM_UTC = 313 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1263318162 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1263321292 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1263318162 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1263321292 refineStartAndEndEsecs(): setting StartEsecs to 1263318161 refineStartAndEndEsecs(): setting EndEsecs to 1263319152 refineStartAndEndEsecs(): numMinBack = 5.48333333333333, numMinForward = 2.81666666666667 refineStartAndEndEsecs(): Refined StartEsecs = 1263319032, Refined EndEsecs = 1263320262 _readLogr(): Reading videologr data from 2010012datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1263319032 & 1263320262 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1263320272, self->{EndEsecs} = 1263320262 _readLogr(): #LOGHOST_SYSTEM_UTC = 122 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1263319042 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1263320262 bin\geopdvc.pl: expdid = 4254 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 = 012 (looping to 012) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010012vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010012vnta.txt esecs, east, north, lat, lon, head, pres 1263319853, 0, 0, 36.878093, -122.122748, 230.2, 18.90 rovNav(): 012: 536 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010012vnta.txt 536 in tList 536 in {self->{rovT}} 536 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1263320025 & 1263319530 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ rovGDCListRefs(): dMedian = 58.9320387518466 rovGDCListRefs(): dStats->sample_range = 89.2738101647826 rovGDCListRefs(): returning (1st elements): 1263319205 36.868617 -121.898407 272.1 2.03482131913772 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 012 (looping to 012) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010012vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010012vntaedited.txt esecs, east, north, lat, lon, head, pres 1263319853, 0, 0, 36.878570, -122.123262, 230.2, 18.90 rovNav(): 012: 536 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010012vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 173 starting at StartEsecs = 1263319032 rovNav(): Initial # seconds to fill at end of dive = 168 ending at EndEsecs =1263320262 rovNav():frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/012/accesses.txt at bin\geopdvc.pl line 2382 LOGHOST_SYSTEM_UTC[0] = 1263319042 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1263320262 rovNav(): numMinBack = 5.31666666666667, numMinForward = 2.81666666666667 rovNav(): Refined StartEsecs = 1263319032, Refined EndEsecs = 1263320262 rovNav(): Refined # seconds to fill at beginning of dive = 173 starting at StartEsecs = 1263319032 rovNav(): Refined # seconds to fill at end of dive = 168 ending at EndEsecs =1263320262 rovNav(): num tList = 536 rovNav(): num eList = 536 rovNav(): Adding data to begining of dive from logr indices 0 .. 16 rovNav(): Adding data to end of dive from logr indices 106 .. 122 rovNav(): num in tList now = 570 rovNav(): num in eList now = 570 570 in tList 570 in {self->{rovT}} 570 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1263320025 & 1263319530 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXX........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ rovGDCListRefs(): dMedian = 54.6352812651801 rovGDCListRefs(): dStats->sample_range = 96.2689260201349 rovGDCListRefs(): returning (1st elements): 1263319042 36.878082 -122.122140 239.8 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.87850400 hash{lon} = -122.12307600 latMin = 30.878504 latMax = 42.878504 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.878504 AND 42.878504 AND Longitude BETWEEN -128.123076 AND -116.123076 Dive lat = 36.87850400, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.12307600 & lat = 36.87850400 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3486rov_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/vnta3486lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 122 shipNav(): $#{$self->{shipLat}}[last] = 36.878138 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 = 571 makeTracks(): Looping through 122 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3486tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2010/vnta/vnta3486mergedCtdNav.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/12/2010 6:02:41 PM' AND '1/12/2010 6:14:53 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/12/2010 6:02:41 PM' AND '1/12/2010 6:14:53 PM') _findIndex(): Looking for epoch secs 1263319361 ... Found time 1263319362 after 5 iterations. >>> p = 26: annotations(): 26, 1263319361, 01/12/10 18:02:41, V3486-01HD, 00:01:30:03, water, , 2.36999988555908, descend, linda, o, 3165130 _findIndex(): Looking for epoch secs 1263319361 ... Found time 1263319362 after 5 iterations. >>> p = 26: annotations(): 26, 1263319361, 01/12/10 18:02:41, V3486-01HD, 00:02:31:01, water, , 2.36999988555908, descend, linda, o, 3165131 _findIndex(): Looking for epoch secs 1263319445 ... Found time 1263319442 after 3 iterations. >>> p = 106: annotations(): 106, 1263319445, 01/12/10 18:04:05, V3486-01HD, 00:03:59:23, water, , 27.1800003051758, descend, linda, o, 3165132 _findIndex(): Looking for epoch secs 1263319501 ... Found time 1263319504 after 5 iterations. >>> p = 168: annotations(): 168, 1263319501, 01/12/10 18:05:01, V3486-01HD, 00:04:56:11, water, , 55.8199996948242, descend, linda, o, 3165133 _findIndex(): Looking for epoch secs 1263319558 ... Found time 1263319558 after 5 iterations. >>> p = 222: annotations(): 222, 1263319558, 01/12/10 18:05:58, V3486-01HD, 00:05:53:01, benthos, , 81.620002746582, descend, linda, o, 3165134 _findIndex(): Looking for epoch secs 1263319558 ... Found time 1263319558 after 5 iterations. >>> p = 222: annotations(): 222, 1263319558, 01/12/10 18:05:58, V3486-01HD, 00:05:53:01, water, , 81.620002746582, descend, linda, o, 3165135 _findIndex(): Looking for epoch secs 1263319598 ... Found time 1263319603 after 4 iterations. >>> p = 267: annotations(): 267, 1263319598, 01/12/10 18:06:38, V3486-01HD, 00:06:33:00, water, , 90.7900009155273, descend, linda, o, 3165136 _findIndex(): Looking for epoch secs 1263319622 ... Found time 1263319621 after 0 iterations. >>> p = 285: annotations(): 285, 1263319622, 01/12/10 18:07:02, V3486-01HD, 00:06:57:15, benthos, , 91.0299987792969, descend, linda, o, 3165137 _findIndex(): Looking for epoch secs 1263319625 ... Found time 1263319621 after 0 iterations. >>> p = 285: annotations(): 285, 1263319625, 01/12/10 18:07:05, V3486-01HD, 00:07:00:22, object, , 90.9300003051758, cruise, linda, o, 3165139 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta3486anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 9.4 db", "T: 13.086 C", "S: 33.31", "O: 5.433 ml/l", "L: 83.26 %"] placeAnnotations(): Placing annotation 4: benthos Ancillary data = ["benthos", "P: 55.7 db", "T: 12.465 C", "S: 33.328", "O: 5.181 ml/l", "L: 81.21 %"] placeAnnotations(): Placing annotation 6: water Ancillary data = ["water", "P: 82.9 db", "T: 11.968 C", "S: 33.377", "O: 4.785 ml/l", "L: 73.51 %"] placeAnnotations(): Placing annotation 8: object Ancillary data = ["object", "P: 91.7 db", "T: 11.645 C", "S: 33.401", "O: 4.257 ml/l", "L: 44.28 %"] inline_name = vnta3486anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: water Ancillary data = ["water", "P: 9.4 db", "T: 13.086 C", "S: 33.31", "O: 5.433 ml/l", "L: 83.26 %"] placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 9.4 db", "T: 13.086 C", "S: 33.31", "O: 5.433 ml/l", "L: 83.26 %"] placeAnnotations(): Placing annotation 3: water Ancillary data = ["water", "P: 24.7 db", "T: 13.003 C", "S: 33.315", "O: 5.416 ml/l", "L: 83.63 %"] placeAnnotations(): Placing annotation 4: benthos Ancillary data = ["benthos", "P: 55.7 db", "T: 12.465 C", "S: 33.328", "O: 5.181 ml/l", "L: 81.21 %"] placeAnframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/012/accesses.txt at bin\geopdvc.pl line 625 frameGrabs(): Can't read dir http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3486: Bad file descriptor at bin\geopdvc.pl line 625 notations(): Placing annotation 5: water Ancillary data = ["water", "P: 82.9 db", "T: 11.968 C", "S: 33.377", "O: 4.785 ml/l", "L: 73.51 %"] placeAnnotations(): Placing annotation 6: water Ancillary data = ["water", "P: 82.9 db", "T: 11.968 C", "S: 33.377", "O: 4.785 ml/l", "L: 73.51 %"] placeAnnotations(): Placing annotation 7: benthos Ancillary data = ["benthos", "P: 91.7 db", "T: 11.683 C", "S: 33.399", "O: 4.389 ml/l", "L: 55.16 %"] placeAnnotations(): Placing annotation 8: object Ancillary data = ["object", "P: 91.7 db", "T: 11.645 C", "S: 33.401", "O: 4.257 ml/l", "L: 44.28 %"] placeAnnotations(): Placing annotation 9: Ancillary data = ["", "P: 91.7 db", "T: 11.645 C", "S: 33.401", "O: 4.257 ml/l", "L: 44.28 %"] inline_name = vnta3486anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3486... iList = bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 0 bin\geopdvc.pl: Calling placeImages with every 10 image... bin\geopdvc.pl: Calling placeImages with every 2 image... bin\geopdvc.pl: Calling placeImages with every 1 image... 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/12/2010 6:02:41 PM' AND '1/12/2010 6:14:53 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2010/vnta/vnta3486.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4254,'/3Dreplay/3D.asp?dives=vnta3486','vnta3486','','','',3486 Executing sql: UPDATE Dive SET DiveLatMid = 36.8785, DiveLonMid = -122.1231, DiveDepthMid = -54.6 WHERE RovName = 'vnta' AND DiveNumber = 3486