Running bin\geopdvc.pl at Tue Jan 25 15:59:16 2011 on host EPHYRA-VM Command line: bin\geopdvc.pl -rov vnta -dive 3487 bin\geopdvc.pl: Getting Expedition object for diveno = 3487 and rname = vnta set_expd(): rov = vnta, diveno = 3487 set_expd(): $self->{EndDDD} = 012 set_expd(): $self->{StartEsecs} = 1263323116 set_expd(): $self->{EndEsecs} = 1263326630 set_expd(): $self->{EndDtg} = 1/12/2010 7:43:50 PM _readLogr(): Reading videologr data from 2010012datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1263323116 & 1263326630 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1263326633, self->{EndEsecs} = 1263326630 _readLogr(): #LOGHOST_SYSTEM_UTC = 350 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1263323123 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1263326623 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1263323123 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1263326623 refineStartAndEndEsecs(): setting StartEsecs to 1263323116 refineStartAndEndEsecs(): setting EndEsecs to 1263324293 refineStartAndEndEsecs(): numMinBack = 2.38333333333333, numMinForward = 20.55 refineStartAndEndEsecs(): Refined StartEsecs = 1263324173, Refined EndEsecs = 1263326663 _readLogr(): Reading videologr data from 2010012datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1263324173 & 1263326663 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1263326673, self->{EndEsecs} = 1263326663 _readLogr(): #LOGHOST_SYSTEM_UTC = 248 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1263324183 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1263326663 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 1263324955, 0, 0, 36.822680, -121.943753, 93.5, 88.00 rovNav(): 012: 703 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010012vnta.txt 703 in tList 703 in {self->{rovT}} 703 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1263325170 & 1263325350 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ rovGDCListRefs(): dMedian = 79.2142819972966 rovGDCListRefs(): dStats->sample_range = 85.5536289305431 rovGDCListRefs(): returning (1st elements): 1263324392 36.878688 -122.123303 291.1 2.00504180465812 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 1263324955, 0, 0, 36.822674, -121.943741, 93.5, 88.00 rovNav(): 012: 703 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010012vntaedited.txt rovNav(): Initial # seconds tframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/012/accesses.txt at bin\geopdvc.pl line 2382 o fill at beginning of dive = 219 starting at StartEsecs = 1263324173 rovNav(): Initial # seconds to fill at end of dive = 1276 ending at EndEsecs =1263326663 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1263324183 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1263326663 rovNav(): numMinBack = 2.21666666666667, numMinForward = 20.55 rovNav(): Refined StartEsecs = 1263324173, Refined EndEsecs = 1263326663 rovNav(): Refined # seconds to fill at beginning of dive = 219 starting at StartEsecs = 1263324173 rovNav(): Refined # seconds to fill at end of dive = 1276 ending at EndEsecs =1263326663 rovNav(): num tList = 703 rovNav(): num eList = 703 rovNav(): Adding data to begining of dive from logr indices 0 .. 20 rovNav(): Adding data to end of dive from logr indices 121 .. 248 rovNav(): num in tList now = 852 rovNav(): num in eList now = 852 852 in tList 852 in {self->{rovT}} 852 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1263325170 & 1263325350 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXX...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X rovGDCListRefs(): dMedian = 60.3811076671175 rovGDCListRefs(): dStats->sample_range = 92.5487448111483 rovGDCListRefs(): returning (1st elements): 1263324183 36.822262 -121.943642 169.2 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.82266700 hash{lon} = -121.94372100 latMin = 30.822667 latMax = 42.822667 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.822667 AND 42.822667 AND Longitude BETWEEN -127.943721 AND -115.943721 Dive lat = 36.82266700, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.94372100 & lat = 36.82266700 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3487rov_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/vnta3487lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 248 shipNav(): $#{$self->{shipLat}}[last] = 36.821105 Position subsampling set to 1 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.4340 0.3672 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.0466 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.5348 0.4652 0.0000 makeTracks(): n = 853 makeTracks(): Looping through 248 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3487tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2010/vnta/vnta3487mergedCtdNav.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 7:25:16 PM' AND '1/12/2010 7:43:50 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/12/2010 7:25:16 PM' AND '1/12/2010 7:43:50 PM') _findIndex(): Looking for epoch secs 1263324382 ... Found time 1263324383 after 8 iterations. >>> p = 20: annotations(): 20, 1263324382, 01/12/10 19:26:22, V3487-01HD, 00:01:38:25, Chrysaora fuscescens, , , descend, linda, o, 3165142 _findIndex(): Looking for epoch secs 1263324382 ... Found time 1263324383 after 8 iterations. >>> p = 20: annotations(): 20, 1263324382, 01/12/10 19:26:22, V3487-01HD, 00:02:50:14, water, , , descend, linda, o, 3165144 _findIndex(): Looking for epoch secs 1263324385 ... Found time 1263324383 after 8 iterations. >>> p = 20: annotations(): 20, 1263324385, 01/12/10 19:26:25, V3487-01HD, 00:01:03:13, water, , 2.10999989509583, descend, linda, o, 3165141 _findIndex(): Looking for epoch secs 1263324442 ... *** Warning: Position time is -38 seconds away from search time. *** >>> p = 22: annotations(): 22, 1263324442, 01/12/10 19:27:22, V3487-01HD, 00:02:02:19, Chrysaora fuscescens, , , descend, linda, o, 3165143 _findIndex(): Looking for epoch secs 1263324490 ... *** Warning: Position time is -16 seconds away from search time. *** >>> p = 26: annotations(): 26, 1263324490, 01/12/10 19:28:10, V3487-01HD, 00:06:26:06, benthos, , , descend, linda, o, 3165152 _findIndex(): Looking for epoch secs 1263324490 ... *** Warning: Position time is -16 seconds away from search time. *** >>> p = 26: annotations(): 26, 1263324490, 01/12/10 19:28:10, V3487-01HD, 00:07:22:06, water, , , cruise, linda, o, 3165153 _findIndex(): Looking for epoch secs 1263324530 ... Found time 1263324530 after 6 iterations. >>> p = 59: annotations(): 59, 1263324530, 01/12/10 19:28:50, V3487-01HD, 00:03:31:11, Chrysaora fuscescens, , 14.289999961853, descend, linda, o, 3165145 _findIndex(): Looking for epoch secs 1263324555 ... Found time 1263324550 after 4 iterations. >>> p = 79: annotations(): 79, 1263324555, 01/12/10 19:29:15, V3487-01HD, 00:03:55:16, Chrysaora fuscescens, , 25.8999996185303, descend, linda, o, 3165146 _findIndex(): Looking for epoch secs 1263324572 ... Found time 1263324577 after 2 iterations. >>> p = 106: annotations(): 106, 1263324572, 01/12/10 19:29:32, V3487-01HD, 00:04:12:10, Chrysaora fuscescens, , 33.0099983215332, descend, linda, o, 3165147 _findIndex(): Looking for epoch secs 1263324591 ... Found time 1263324590 after 5 iterations. >>> p = 119: annotations(): 119, 1263324591, 01/12/10 19:29:51, V3487-01HD, 00:04:31:14, Chrysaora fuscescens, , 42.4700012207031, descend, linda, o, 3165148 _findIndex(): Looking for epoch secs 1263324618 ... Found time 1263324616 after 5 iterations. >>> p = 145: annotations(): 145, 1263324618, 01/12/10 19:30:18, V3487-01HD, 00:04:59:11, Chrysaora fuscescens, , 49.1300010681152, descend, linda, o, 3165149 _findIndex(): Looking for epoch secs 1263324637 ... Found time 1263324636 after 6 iterations. >>> p = 165: annotations(): 165, 1263324637, 01/12/10 19:30:37, V3487-01HD, 00:05:17:27, Chrysaora fuscescens, , 57.9000015258789, descend, linda, o, 3165150 _findIndex(): Looking for epoch secs 1263324681 ... Found time 1263324684 after 1 iterations. >>> p = 213: annotations(): 213, 1263324681, 01/12/10 19:31:21, V3487-01HD, 00:06:02:10, water, , 79.4599990844727, descend, linda, o, 3165151 _findIndex(): Looking for epoch secs 1263324762 ... Found time 1263324764 after 4 iterations. >>> p = 292: annotations(): 292, 1263324762, 01/12/10 19:32:42, V3487-01HD, 00:10:01:24, water, , 87.4400024414063, ascend, linda, o, 3165156 _findIndex(): Looking for epoch secs 1263324838 ... Found time 1263324837 after 6 iterations. >>> p = 365: annotations(): 365, 1263324838, 01/12/10 19:33:58, V3487-01HD, 00:08:37:12, water, , 86.9199981689453, cruise, linda, o, 3165154 _findIndex(): Looking for epoch secs 1263324905 ... Found time 1263324904 after 6 iterations. >>> p = 432: annotations(): 432, 1263324905, 01/12/10 19:35:05, V3487-01HD, 00:09:44:18, water, , 85.4100036621094, cruise, linda, o, 3165155 _findIndex(): Looking for epoch secs 1263324988 ... Found time 1263324990 after 5 iterations. >>> p = 518: annotations(): 518, 1263324988, 01/12/10 19:36:28, V3487-01HD, 00:11:08:19, water, , 86.7099990844727, ascend, linda, o, 3165157 _findIndex(): Looking for epoch secs 1263325043 ... Found time 1263325043 after 5 iterations. >>> p = 571: annotations(): 571, 1263325043, 01/12/10 19:37:23, V3487-01HD, 00:12:03:13, water, , 74.3000030517578, ascend, linda, o, 3165158 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 40.9 db", "T: 12.406 C", "S: 33.343", "O: 5.182 ml/l", "L: 82.16 %"] inline_name = vnta3487anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 2.9 db", "T: 12.67 C", "S: 33.275", "O: 5.403 ml/l", "L: 78.99 %"] placeAnnotations(): Placing annotation 4: benthos Ancillary data = ["benthos", "P: 2.8 db", "T: 12.673 C", "S: 33.273", "O: 5.411 ml/l", "L: 77.37 %"] placeAnnotations(): Placing annotation 6: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 2.9 db", "T: 12.67 C", "S: 33.275", "O: 5.403 ml/l", "L: 78.99 %"] placeAnnotations(): Placing annotation 8: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 27.2 db", "T: 12.545 C", "S: 33.335", "O: 5.361 ml/l", "L: 81.11 %"] placeAnnotations(): Placing annotation 10: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 40.9 db", "T: 12.406 C", "S: 33.343", "O: 5.182 ml/l", "L: 82.16 %"] placeAnnotations(): Placing annotation 12: water Ancillary data = ["water", "P: 55.8 db", "T: 12.199 C", "S: 33.358", "O: 4.921 ml/l", "L: 81.66 %"] placeAnnotations(): Placing annotation 14: water Ancillary data = ["water", "P: 89.1 db", "T: 11.756 C", "S: 33.429", "O: 4.165 ml/l", "L: 61.62 %"] placeAnnotations(): Placing annotation 16: water Ancillary data = ["water", "P: 88.4 db", "T: 11.751 C", "S: 33.429", "O: 4.015 ml/l", "L: 54.3 %"] placeAnnotations(): Placing annotation 18: Ancillary data = ["", "P: 72.3 db", "T: 11.964 C", "S: 33.397", "O: 4.16 ml/l", "L: 78.4 %"] inline_name = vnta3487anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: water Ancillary data = ["water", "P: 2.9 db", "T: 12.67 C", "S: 33.275", "O: 5.403 ml/l", "L: 78.99 %"] placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 2.9 db", "T: 12.67 C", "S: 33.275", "O: 5.403 ml/l", "L: 78.99 %"] placeAnnotations(): Placing annotation 3: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 2.9 db", "T: 12.67 C", "S: 33.275", "O: 5.403 ml/l", "L: 78.99 %"] placeAnnotations(): Placing annotation 4: benthos Ancillary data = ["benthos", "P: 2.8 db", "T: 12.673 C", "S: 33.273", "O: 5.411 ml/l", "L: 77.37 %"] placeAnnotations(): Placing annotation 5: water Ancillary data = ["water", "P: 2.9 db", "T: 12.67 C", "S: 33.275", "O: 5.403 ml/l", "L: 78.99 %"] placeAnnotations(): Placing annotation 6: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 2.9 db", "T: 12.67 C", "S: 33.275", "O: 5.403 ml/l", "L: 78.99 %"] placeAnnotations(): Placing annotation 7: CframeGrabs(): 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/vnta3487: Bad file descriptor at bin\geopdvc.pl line 625 hrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 13.4 db", "T: 12.641 C", "S: 33.279", "O: 5.405 ml/l", "L: 79.07 %"] placeAnnotations(): Placing annotation 8: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 27.2 db", "T: 12.545 C", "S: 33.335", "O: 5.361 ml/l", "L: 81.11 %"] placeAnnotations(): Placing annotation 9: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 33.5 db", "T: 12.485 C", "S: 33.335", "O: 5.276 ml/l", "L: 81.5 %"] placeAnnotations(): Placing annotation 10: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 40.9 db", "T: 12.406 C", "S: 33.343", "O: 5.182 ml/l", "L: 82.16 %"] placeAnnotations(): Placing annotation 11: Chrysaora fuscescens Ancillary data = ["Chrysaora fuscescens", "P: 50 db", "T: 12.291 C", "S: 33.35", "O: 5.006 ml/l", "L: 81.91 %"] placeAnnotations(): Placing annotation 12: water Ancillary data = ["water", "P: 55.8 db", "T: 12.199 C", "S: 33.358", "O: 4.921 ml/l", "L: 81.66 %"] placeAnnotations(): Placing annotation 13: water Ancillary data = ["water", "P: 84 db", "T: 11.943 C", "S: 33.4", "O: 4.524 ml/l", "L: 79.48 %"] placeAnnotations(): Placing annotation 14: water Ancillary data = ["water", "P: 89.1 db", "T: 11.756 C", "S: 33.429", "O: 4.165 ml/l", "L: 61.62 %"] placeAnnotations(): Placing annotation 15: water Ancillary data = ["water", "P: 89 db", "T: 11.748 C", "S: 33.429", "O: 4.034 ml/l", "L: 53.5 %"] placeAnnotations(): Placing annotation 16: water Ancillary data = ["water", "P: 88.4 db", "T: 11.751 C", "S: 33.429", "O: 4.015 ml/l", "L: 54.3 %"] placeAnnotations(): Placing annotation 17: water Ancillary data = ["water", "P: 89 db", "T: 11.752 C", "S: 33.429", "O: 4.021 ml/l", "L: 53.34 %"] placeAnnotations(): Placing annotation 18: Ancillary data = ["", "P: 72.3 db", "T: 11.964 C", "S: 33.397", "O: 4.16 ml/l", "L: 78.4 %"] inline_name = vnta3487anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3487... 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 7:25:16 PM' AND '1/12/2010 7:43:50 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2010/vnta/vnta3487.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4254,'/3Dreplay/3D.asp?dives=vnta3487','vnta3487','','','',3487 Executing sql: UPDATE Dive SET DiveLatMid = 36.8227, DiveLonMid = -121.9437, DiveDepthMid = -60.4 WHERE RovName = 'vnta' AND DiveNumber = 3487