Running bin\geopdvc.pl at Sun Feb 14 03:00:15 2010 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3473 bin\geopdvc.pl: Getting Expedition object for diveno = 3473 and rname = vnta set_expd(): rov = vnta, diveno = 3473 set_expd(): $self->{EndDDD} = 351 set_expd(): $self->{StartEsecs} = 1261064880 set_expd(): $self->{EndEsecs} = 1261071060 set_expd(): $self->{EndDtg} = 12/17/2009 5:11:00 PM _readLogr(): Reading videologr data from 2009351datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1261064880 & 1261071060 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1261071064, self->{EndEsecs} = 1261071060 _readLogr(): #LOGHOST_SYSTEM_UTC = 617 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1261064883 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1261071054 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1261064883 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1261071054 refineStartAndEndEsecs(): setting StartEsecs to 1261064880 refineStartAndEndEsecs(): setting EndEsecs to 1261064893 refineStartAndEndEsecs(): numMinBack = 21.7833333333333, numMinForward = 21.7333333333333 refineStartAndEndEsecs(): Refined StartEsecs = 1261064773, Refined EndEsecs = 1261071164 _readLogr(): Reading videologr data from 2009351datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1261064773 & 1261071164 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1261071174, self->{EndEsecs} = 1261071164 _readLogr(): #LOGHOST_SYSTEM_UTC = 638 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1261064783 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1261071164 bin\geopdvc.pl: expdid = 4240 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 = 351 (looping to 351) Does file exist?: \\tornado\TempNav\2009\vnta\nav2009351vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2009\vnta\nav2009351vnta.txt esecs, east, north, lat, lon, head, pres 1261066662, 0, 0, 36.769100, -121.889465, 251.0, 149.91 1261067163, 0, 0, 36.768670, -121.891068, 278.7, 252.29 1261067664, 0, 0, 36.768702, -121.891248, 287.3, 252.96 1261068165, 0, 0, 36.769003, -121.892118, 129.0, 259.46 1261068666, 0, 0, 36.769208, -121.891260, 89.6, 194.42 1261069167, 0, 0, 36.769068, -121.890312, 51.8, 19.87 1261070476, 0, 0, 36.767867, -121.890520, 191.5, 135.26 1261070977, 0, 0, 36.768737, -121.891910, 351.6, 251.65 rovNav(): 351: 4185 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009351vnta.txt 4185 in tList 4185 in {self->{rovT}} 4185 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1261069005 & 1261067025 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X..XX rovGDCListRefs(): dMedian = 215.421203098205 rovGDCListRefs(): dStats->sample_range = 255.989296513287 rovGDCListRefs(): returning (1st elements): 1261066092 36.717478 -122.187445 146.4 2.02492249360722 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 351 (looping to 351) Does file exist?: \\tornado\TempNav\2009\vnta\nav2009351vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2009\vnta\nav2009351vntaedited.txt esecs, east, north, lat, lon, head, pres 1261066662, 0, 0, 36.769104, -121.889437, 251.0, 149.91 1261067163, 0, 0, 36.768671, -121.891067, 278.7, 252.29 1261067664, 0, 0, 36.768694, -121.891250, 287.3, 252.96 1261068165, 0, 0, 36.768997, -121.892108, 129.0, 259.46 1261068666, 0, 0, 36.769174, -121.891272, 89.6, 194.42 1261069167, 0, 0, 36.769068, -121.890329, 51.8, 19.87 1261070476, 0, 0, 36.767874, -121.890511, 191.5, 135.26 1261070977, 0, 0, 36.768722, -121.891924, 351.6, 251.65 rovNav(): 351: 4185 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009351vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 1319 starting at StartEsecs = 1261064773 rovNav(): Initial # seconds to fill at end of dive = 0 ending at EndEsecs =1261071164 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1261064783 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1261071164 rovNav(): numMinBack = 21.6166666666667, numMinForward = 21.7333333333333 rovNav(): Refined StartEsecs = 1261064773, Refined EndEsecs = 1261071164 rovNav(): Refined # seconds to fill at beginning of dive = 1319 starting at StartEsecs = 1261064773 rovNav(): Refined # seconds to fill at end of dive = 0 ending at EndEsecs =1261071164 rovNav(): num tList = 4185 rovNav(): num eList = 4185 rovNav(): Adding data to begining of dive from logr indices 0 .. 130 rovNav(): Adding data to end of dive from logr indices 638 .. 638 rovNav(): num in tList now = 4317 rovNav(): num in eList now = 4317 4317 in tList 4317 in {self->{rovT}} 4317 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1261069005 & 1261067025 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXXXXXX..........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X..XX rovGDCListRefs(): dMedian = 208.311594026564 rovGDCListRefs(): dStats->sample_range = 262.48877611281 rovGDCListRefs(): returning (1st elements): 1261064783 36.770887 -121.888682 54.5 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.76892850 hash{lon} = -121.89099900 latMin = 30.7689285 latMax = 42.7689285 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigiframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/351/accesses.txt at bin\geopdvc.pl line 2382 n WHERE Latitude BETWEEN 30.7689285 AND 42.7689285 AND Longitude BETWEEN -127.890999 AND -115.890999 Dive lat = 36.76892850, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.89099900 & lat = 36.76892850 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3473rov_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 = geo2009/vnta/vnta3473lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 638 shipNav(): $#{$self->{shipLat}}[last] = 36.768075 Position subsampling set to 4 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.6775 0.5775 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.0976 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.4906 0.5094 0.0000 makeTracks(): n = 4318 makeTracks(): Looping through 638 point of ship position data... makeTracks(): posSubsample = 4 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3473tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2009/vnta/vnta3473mergedCtdNav.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 '12/17/2009 4:08:00 PM' AND '12/17/2009 5:11:00 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '12/17/2009 4:08:00 PM' AND '12/17/2009 5:11:00 PM') _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039834 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039835 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039836 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039837 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039838 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039839 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039840 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039841 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039842 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039843 _findIndex(): Looking for epoch secs 1261067516 ... Found time 1261067516 after 4 iterations. >>> p = 1483: annotations(): 1483, 1261067516, 12/17/09 16:31:56, V3463-01, 00:00:00:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3463/00_00_00_00.jpg, , descend, whpa, o, 20039844 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] inline_name = vnta3473anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] inline_name = vnta3473anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotaframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/351/accesses.txt at bin\geopdvc.pl line 625 frameGrabs(): Can't read dir http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/vnta3473: Bad file descriptor at bin\geopdvc.pl line 625 tion 5: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] placeAnnotations(): Placing annotation 11: Ancillary data = ["", "P: 254.4 db", "T: 8.629 C", "S: 33.952", "O: 1.602 ml/l", "L: 82.05 %"] inline_name = vnta3473anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/vnta3473... 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 '12/17/2009 4:08:00 PM' AND '12/17/2009 5:11:00 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2009/vnta/vnta3473.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4240,'/3Dreplay/3D.asp?dives=vnta3473','vnta3473','','','',3473 Executing sql: UPDATE Dive SET DiveLatMid = 36.7689, DiveLonMid = -121.8910, DiveDepthMid = -208.3 WHERE RovName = 'vnta' AND DiveNumber = 3473