Running bin\geopdvc.pl at Tue Jan 25 16:05:10 2011 on host EPHYRA-VM Command line: bin\geopdvc.pl -rov vnta -dive 3489 bin\geopdvc.pl: Getting Expedition object for diveno = 3489 and rname = vnta set_expd(): rov = vnta, diveno = 3489 set_expd(): $self->{EndDDD} = 014 set_expd(): $self->{StartEsecs} = 1263487368 set_expd(): $self->{EndEsecs} = 1263490704 set_expd(): $self->{EndDtg} = 1/14/2010 5:18:24 PM _readLogr(): Reading videologr data from 2010014datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1263487368 & 1263490704 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1263490707, self->{EndEsecs} = 1263490704 _readLogr(): #LOGHOST_SYSTEM_UTC = 332 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1263487376 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1263490697 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1263487376 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1263490697 refineStartAndEndEsecs(): setting StartEsecs to 1263487368 refineStartAndEndEsecs(): setting EndEsecs to 1263487506 refineStartAndEndEsecs(): numMinBack = 19.7, numMinForward = 4.71666666666667 refineStartAndEndEsecs(): Refined StartEsecs = 1263487386, Refined EndEsecs = 1263489787 _readLogr(): Reading videologr data from 2010014datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1263487386 & 1263489787 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1263489797, self->{EndEsecs} = 1263489787 _readLogr(): #LOGHOST_SYSTEM_UTC = 239 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1263487396 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1263489787 bin\geopdvc.pl: expdid = 4256 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 = 014 (looping to 014) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010014vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010014vnta.txt esecs, east, north, lat, lon, head, pres 1263489200, 0, 0, 36.877932, -122.122500, 272.2, 65.06 rovNav(): 014: 631 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010014vnta.txt 631 in tList 631 in {self->{rovT}} 631 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1263488655 & 1263488775 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X rovGDCListRefs(): dMedian = 64.741945878181 rovGDCListRefs(): dStats->sample_range = 88.5396134528173 rovGDCListRefs(): returning (1st elements): 1263488583 36.846767 -122.133542 315.6 2.5608987152874 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 014 (looping to 014) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010014vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010014vntaedited.txt esecs, east, north, lat, lon, head, pres 1263489200, 0, 0, 36.877968, -122.122490, 272.2, 65.06 rovNav(): 014: 631 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010014vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 1197 starting at StartEsecs = 1263487386 rovframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/014/accesses.txt at bin\geopdvc.pl line 2382 Nav(): Initial # seconds to fill at end of dive = 295 ending at EndEsecs =1263489787 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1263487396 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1263489787 rovNav(): numMinBack = 19.5333333333333, numMinForward = 4.71666666666667 rovNav(): Refined StartEsecs = 1263487386, Refined EndEsecs = 1263489787 rovNav(): Refined # seconds to fill at beginning of dive = 1197 starting at StartEsecs = 1263487386 rovNav(): Refined # seconds to fill at end of dive = 295 ending at EndEsecs =1263489787 rovNav(): num tList = 631 rovNav(): num eList = 631 rovNav(): Adding data to begining of dive from logr indices 0 .. 118 rovNav(): Adding data to end of dive from logr indices 210 .. 239 rovNav(): num in tList now = 780 rovNav(): num in eList now = 780 780 in tList 780 in {self->{rovT}} 780 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1263488655 & 1263488775 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXX..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X rovGDCListRefs(): dMedian = 47.6392124483694 rovGDCListRefs(): dStats->sample_range = 95.5347346918048 rovGDCListRefs(): returning (1st elements): 1263487396 36.874840 -122.114947 173.6 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.87803800 hash{lon} = -122.12201200 latMin = 30.878038 latMax = 42.878038 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.878038 AND 42.878038 AND Longitude BETWEEN -128.122012 AND -116.122012 Dive lat = 36.87803800, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.12201200 & lat = 36.87803800 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3489rov_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/vnta3489lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 239 shipNav(): $#{$self->{shipLat}}[last] = 36.877092 Position subsampling set to 1 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.7213 0.6213 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.0678 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.6114 0.3886 0.0000 makeTracks(): n = 781 makeTracks(): Looping through 239 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3489tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2010/vnta/vnta3489mergedCtdNav.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/14/2010 5:02:48 PM' AND '1/14/2010 5:18:24 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/14/2010 5:02:48 PM' AND '1/14/2010 5:18:24 PM') _findIndex(): Looking for epoch secs 1263488834 ... Found time 1263488836 after 5 iterations. >>> p = 255: annotations(): 255, 1263488834, 01/14/10 17:07:14, V3489-01HD, 00:01:23:13, Nanomia bijuga, , 77.8099975585938, descend, linda, o, 3164948 _findIndex(): Looking for epoch secs 1263488835 ... Found time 1263488836 after 5 iterations. >>> p = 255: annotations(): 255, 1263488835, 01/14/10 17:07:15, V3489-01HD, 00:00:03:28, water, , 40, descend, linda, o, 3164946 _findIndex(): Looking for epoch secs 1263488868 ... Found time 1263488873 after 2 iterations. >>> p = 292: annotations(): 292, 1263488868, 01/14/10 17:07:48, V3489-01HD, 00:00:36:28, water, , 57.8899993896484, descend, linda, o, 3164947 _findIndex(): Looking for epoch secs 1263488882 ... Found time 1263488885 after 5 iterations. >>> p = 304: annotations(): 304, 1263488882, 01/14/10 17:08:02, V3489-01HD, 00:00:49:21, water, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3489/00_00_49_21.jpg, 63.7900009155273, descend, linda, o, 20040219 _findIndex(): Looking for epoch secs 1263488914 ... Found time 1263488909 after 5 iterations. >>> p = 328: annotations(): 328, 1263488914, 01/14/10 17:08:34, V3489-01HD, 00:01:35:22, Mitrocoma, , 81.4700012207031, descend, linda, o, 3164949 _findIndex(): Looking for epoch secs 1263488966 ... Found time 1263488971 after 0 iterations. >>> p = 390: annotations(): 390, 1263488966, 01/14/10 17:09:26, V3489-01HD, 00:02:14:23, benthos, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3489/00_02_14_23.jpg, 89.7200012207031, descend, linda, o, 3164957 _findIndex(): Looking for epoch secs 1263488999 ... Found time 1263488995 after 4 iterations. >>> p = 414: annotations(): 414, 1263488999, 01/14/10 17:09:59, V3489-01HD, 00:02:48:10, object, , 90.0100021362305, descend, linda, o, 3165043 _findIndex(): Looking for epoch secs 1263488999 ... Found time 1263488995 after 4 iterations. >>> p = 414: annotations(): 414, 1263488999, 01/14/10 17:09:59, V3489-01HD, 00:02:50:15, water, , 89.9300003051758, descend, linda, o, 3165045 _findIndex(): Looking for epoch secs 1263489001 ... Found time 1263489001 after 6 iterations. >>> p = 420: annotations(): 420, 1263489001, 01/14/10 17:10:01, V3489-01HD, 00:03:23:17, Nanomia bijuga, , 90.2600021362305, ascend, linda, o, 3165060 _findIndex(): Looking for epoch secs 1263489017 ... Found time 1263489019 after 3 iterations. >>> p = 438: annotations(): 438, 1263489017, 01/14/10 17:10:17, V3489-01HD, 00:03:05:23, water, , 89.8899993896484, cruise, linda, o, 3165051 _findIndex(): Looking for epoch secs 1263489034 ... Found time 1263489031 after 5 iterations. >>> p = 450: annotations(): 450, 1263489034, 01/14/10 17:10:34, V3489-01HD, 00:03:23:03, water, , 90.1900024414063, cruise, linda, o, 3165055 _findIndex(): Looking for epoch secs 1263489040 ... Found time 1263489043 after 4 iterations. >>> p = 462: annotations(): 462, 1263489040, 01/14/10 17:10:40, V3489-01HD, 00:03:28:06, water, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3489/00_03_28_06.jpg, 90.0500030517578, ascend, linda, o, 20040220 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: water Ancillary data = ["water", "P: 90.7 db", "T: 11.994 C", "S: 33.381", "O: 4.21 ml/l", "L: 49.82 %"] inline_name = vnta3489anno10.wrl bin\geframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/014/accesses.txt at bin\geopdvc.pl line 625 opdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 40.1 db", "T: 13.066 C", "S: 33.316", "O: 5.331 ml/l", "L: 84.81 %"] placeAnnotations(): Placing annotation 4: Mitrocoma Ancillary data = ["Mitrocoma", "P: 63.3 db", "T: 12.942 C", "S: 33.319", "O: 5.172 ml/l", "L: 85.26 %"] placeAnnotations(): Placing annotation 6: object Ancillary data = ["object", "P: 90.4 db", "T: 12.007 C", "S: 33.38", "O: 4.316 ml/l", "L: 70.38 %"] placeAnnotations(): Placing annotation 8: Nanomia bijuga Ancillary data = ["Nanomia bijuga", "P: 90.7 db", "T: 11.995 C", "S: 33.381", "O: 4.236 ml/l", "L: 53.96 %"] placeAnnotations(): Placing annotation 10: water Ancillary data = ["water", "P: 90.7 db", "T: 11.994 C", "S: 33.381", "O: 4.21 ml/l", "L: 49.82 %"] placeAnnotations(): Placing annotation 12: Ancillary data = ["", "P: 90.8 db", "T: 11.988 C", "S: 33.381", "O: 4.183 ml/l", "L: 47.2 %"] inline_name = vnta3489anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: water Ancillary data = ["water", "P: 40.1 db", "T: 13.066 C", "S: 33.316", "O: 5.331 ml/l", "L: 84.81 %"] placeAnnotations(): Placing annotation 2: water Ancillary data = ["water", "P: 40.1 db", "T: 13.066 C", "S: 33.316", "O: 5.331 ml/l", "L: 84.81 %"] placeAnnotations(): Placing annotation 3: water Ancillary data = ["water", "P: 63.3 db", "T: 12.942 C", "S: 33.319", "O: 5.172 ml/l", "L: 85.26 %"] placeAnnotations(): Placing annotation 4: Mitrocoma Ancillary data = ["Mitrocoma", "P: 63.3 db", "T: 12.942 C", "S: 33.319", "O: 5.172 ml/l", "L: 85.26 %"] placeAnnotations(): Placing annotation 5: benthos Ancillary data = ["benthos", "P: 76 db", "T: 12.303 C", "S: 33.351", "O: 4.732 ml/l", "L: 83.22 %"] placeAnnotations(): Placing annotation 6: object Ancillary data = ["object", "P: 90.4 db", "T: 12.007 C", "S: 33.38", "O: 4.316 ml/l", "L: 70.38 %"] placeAnnotations(): Placing annotation 7: water Ancillary data = ["water", "P: 90.7 db", "T: 11.995 C", "S: 33.381", "O: 4.236 ml/l", "L: 53.96 %"] placeAnnotations(): Placing annotation 8: Nanomia bijuga Ancillary data = ["Nanomia bijuga", "P: 90.7 db", "T: 11.995 C", "S: 33.381", "O: 4.236 ml/l", "L: 53.96 %"] placeAnnotations(): Placing annotation 9: water Ancillary data = ["water", "P: 90.7 db", "T: 11.995 C", "S: 33.381", "O: 4.236 ml/l", "L: 53.96 %"] placeAnnotations(): Placing annotation 10: water Ancillary data = ["water", "P: 90.7 db", "T: 11.994 C", "S: 33.381", "O: 4.21 ml/l", "L: 49.82 %"] placeAnnotations(): Placing annotation 11: water Ancillary data = ["water", "P: 90.8 db", "T: 11.994 C", "S: 33.381", "O: 4.193 ml/l", "L: 56.12 %"] placeAnnotations(): Placing annotation 12: Ancillary data = ["", "P: 90.8 db", "T: 11.988 C", "S: 33.381", "O: 4.183 ml/l", "L: 47.2 %"] inline_name = vnta3489anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3489... frameGrabs(): matched 00_00_49_21 in [IMG]00_00_49_21.jpg26-Jan-2010 00:55 50K frameGrabs(): matched 00_03_28_06 in [IMG]00_03_28_06.jpg26-Jan-2010 00:55 50K iList = 00_00_49_21.jpg 00_03_28_06.jpg _findIndex(): Looking for epoch secs 1263488882 ... Found time 1263488885 after 5 iterations. >>> p = 304: frameGrabs(): index = 304 EXPD::frameGrabs(): 304: Time=1263488880, Depth=62.82, Lat=36.878233, Lon=-122.121982, Pres=63.3, Temp=12.942, Sal=33.319, O2=5.172, Light=85.26 _findIndex(): Looking for epoch secs 1263489040 ... Found time 1263489043 after 4 iterations. >>> p = 462: frameGrabs(): index = 462 EXPD::frameGrabs(): 462: Time=1263489045, Depth=90.11, Lat=36.877999, Lon=-122.121951, Pres=90.8, Temp=11.988, Sal=33.381, O2=4.183, Light=47.2 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 2 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_00_49_21.jpg 1263488882 fw= 0.0 cm XplaceImages(): indx = 304, geoPosition = 36.878233, -122.121982, -65.8582615670283, rovDepth = 65.8582615670283 inline_name = vnta3489fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_00_49_21.jpg 1263488882 fw= 0.0 cm XplaceImages(): indx = 304, geoPosition = 36.878233, -122.121982, -65.8582615670283, rovDepth = 65.8582615670283 inline_name = vnta3489fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_00_49_21.jpg 1263488882 fw= 0.0 cm XplaceImages(): indx = 304, geoPosition = 36.878233, -122.121982, -65.8582615670283, rovDepth = 65.8582615670283 placeImages(): Placing image 1 00_03_28_06.jpg 1263489040 fw= 0.0 cm .placeImages(): indx = 462, geoPosition = 36.877999, -122.121951, -89.8997521044989, rovDepth = 89.8997521044989 inline_name = vnta3489fg01.wrl 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/14/2010 5:02:48 PM' AND '1/14/2010 5:18:24 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2010/vnta/vnta3489.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4256,'/3Dreplay/3D.asp?dives=vnta3489','vnta3489','','','',3489 Executing sql: UPDATE Dive SET DiveLatMid = 36.8780, DiveLonMid = -122.1220, DiveDepthMid = -47.6 WHERE RovName = 'vnta' AND DiveNumber = 3489