Running bin\geopdvc.pl at Sun Feb 7 03:00:17 2010 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3469 bin\geopdvc.pl: Getting Expedition object for diveno = 3469 and rname = vnta set_expd(): rov = vnta, diveno = 3469 set_expd(): $self->{EndDDD} = 344 set_expd(): $self->{StartEsecs} = 1260459608 set_expd(): $self->{EndEsecs} = 1260464693 set_expd(): $self->{EndDtg} = 12/10/2009 4:44:53 PM _readLogr(): Reading videologr data from 2009344datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1260459608 & 1260464693 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1260464700, self->{EndEsecs} = 1260464693 _readLogr(): #LOGHOST_SYSTEM_UTC = 508 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1260459609 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1260464690 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1260459609 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1260464690 refineStartAndEndEsecs(): setting StartEsecs to 1260459608 refineStartAndEndEsecs(): setting EndEsecs to 1260459729 refineStartAndEndEsecs(): numMinBack = 19.9833333333333, numMinForward = 21.7833333333333 refineStartAndEndEsecs(): Refined StartEsecs = 1260459609, Refined EndEsecs = 1260464800 _readLogr(): Reading videologr data from 2009344datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1260459609 & 1260464800 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1260464810, self->{EndEsecs} = 1260464800 _readLogr(): #LOGHOST_SYSTEM_UTC = 518 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1260459619 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1260464800 bin\geopdvc.pl: expdid = 4234 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 = 344 (looping to 344) Does file exist?: \\tornado\TempNav\2009\vnta\nav2009344vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2009\vnta\nav2009344vnta.txt esecs, east, north, lat, lon, head, pres 1260461309, 0, 0, 36.769023, -121.892013, 225.6, 166.01 1260461810, 0, 0, 36.768882, -121.892142, 112.6, 257.84 1260462311, 0, 0, 36.768918, -121.892082, 175.1, 257.94 1260462812, 0, 0, 36.768537, -121.891745, 143.0, 201.10 1260463313, 0, 0, 36.768953, -121.892010, 143.2, 9.49 rovNav(): 344: 2687 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009344vnta.txt 2687 in tList 2687 in {self->{rovT}} 2687 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1260462810 & 1260461505 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 215.718670720761 rovGDCListRefs(): dStats->sample_range = 254.115641083149 rovGDCListRefs(): returning (1st elements): 1260460809 36.813323 -121.973808 84.0 2.07453510310127 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 344 (looping to 344) Does file exist?: \\tornado\TempNav\2009\vnta\nav2009344vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2009\vnta\nav2009344vntaedited.txt esecs, east, north, lat, lon, head, pres 1260461309, 0, 0, 36.769005, -121.892014, 225.6, 166.01 1260461810, 0, 0, 36.768901, -121.892106, 112.6, 257.84 1260462311, 0, 0, 36.768906, -121.892087, 175.1, 257.94 1260462812, 0, 0, 36.768534, -121.891735, 143.0, 201.10 1260463313, 0, 0, 36.768939, -121.892027, 143.2, 9.49 rovNav(): 344: 2687 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009344vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 1200 starting at StartEsecs = 1260459609 rovNav(): Initial # seconds to fill at end of dive = 16 ending at EndEsecs =1260464800 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1260459619 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1260464800 rovNav(): numMinBack = 19.8166666666667, numMinForward = 21.7833333333333 rovNav(): Refined StartEsecs = 1260459609, Refined EndEsecs = 1260464800 rovNav(): Refined # seconds to fill at beginning of dive = 1200 starting at StartEsecs = 1260459609 rovNav(): Refined # seconds to fill at end of dive = 16 ending at EndEsecs =1260464800 rovNav(): num tList = 2687 rovNav(): num eList = 2687 rovNav(): Adding data to begining of dive from logr indices 0 .. 119 rovNav(): Adding data to end of dive from logr indices 517 .. 518 rovNav(): num in tList now = 2809 rovNav(): num in eList now = 2809 2809 in tList 2809 in {self->{rovT}} 2809 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1260462810 & 1260461505 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXX.................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 201.692582575704 rovGDCListRefs(): dStats->sample_range = 261.120702303043 rovGDCListRefs(): returning (1st elements): 1260459619 36.771488 -121.885238 186.3 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.76890200 hash{lon} = -121.89205600 latMin = 30.768902 latMax = 42.768902 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.768902 AND 42.768902 AND Longitude BETWEEN -127.892056 AND -115.892056 Dive lat = 36.76890200, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.89205600 & lat = 36.76890200 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3469rov_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/vnta3469lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 518 shipNav(): $#{$self->{shipLat}}[last] = 36.768265 Position subsampling set to 3 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.frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/344/accesses.txt at bin\geopdvc.pl line 2382 .. varColors = SalColors index0 = 1 1 .1 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0 0 1 makeTracks(): n = 2810 makeTracks(): Looping through 518 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3469tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2009/vnta/vnta3469mergedCtdNav.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/10/2009 4:00:08 PM' AND '12/10/2009 4:44:53 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '12/10/2009 4:00:08 PM' AND '12/10/2009 4:44:53 PM') _findIndex(): Looking for epoch secs 1260461638 ... Found time 1260461634 after 6 iterations. >>> p = 943: annotations(): 943, 1260461638, 12/10/09 16:13:58, V3469-01, 00:00:00:00, object, , , descend, whpa, o, 20039798 _findIndex(): Looking for epoch secs 1260461638 ... Found time 1260461634 after 6 iterations. >>> p = 943: annotations(): 943, 1260461638, 12/10/09 16:13:58, V3469-01, 00:00:00:00, object, , , descend, whpa, o, 20039799 _findIndex(): Looking for epoch secs 1260461638 ... Found time 1260461634 after 6 iterations. >>> p = 943: annotations(): 943, 1260461638, 12/10/09 16:13:58, V3469-01, 00:00:00:00, object, , , descend, whpa, o, 20039800 _findIndex(): Looking for epoch secs 1260462449 ... Found time 1260462447 after 2 iterations. >>> p = 1755: annotations(): 1755, 1260462449, 12/10/09 16:27:29, V3465-04HD, 06:03:58:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_03_58_12.jpg, , ascend, reki, o, 20039801 _findIndex(): Looking for epoch secs 1260462479 ... Found time 1260462479 after 7 iterations. >>> p = 1787: annotations(): 1787, 1260462479, 12/10/09 16:27:59, V3465-04HD, 06:04:29:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_04_29_11.jpg, , ascend, reki, o, 20039802 _findIndex(): Looking for epoch secs 1260462502 ... Found time 1260462501 after 7 iterations. >>> p = 1809: annotations(): 1809, 1260462502, 12/10/09 16:28:22, V3465-04HD, 06:04:53:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_04_53_02.jpg, , ascend, reki, o, 20039803 _findIndex(): Looking for epoch secs 1260462513 ... Found time 1260462512 after 6 iterations. >>> p = 1820: annotations(): 1820, 1260462513, 12/10/09 16:28:33, V3465-04HD, 06:04:57:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_04_57_02.jpg, , ascend, reki, o, 20039804 _findIndex(): Looking for epoch secs 1260462513 ... Found time 1260462512 after 6 iterations. >>> p = 1820: annotations(): 1820, 1260462513, 12/10/09 16:28:33, V3465-04HD, 06:05:04:16, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_05_04_16.jpg, , ascend, reki, o, 20039805 _findIndex(): Looking for epoch secs 1260462515 ... Found time 1260462512 after 6 iterations. >>> p = 1820: annotations(): 1820, 1260462515, 12/10/09 16:28:35, V3465-04HD, 06:05:06:16, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_05_06_16.jpg, , ascend, reki, o, 20039806 _findIndex(): Looking for epoch secs 1260462530 ... Found time 1260462534 after 4 iterations. >>> p = 1842: annotations(): 1842, 1260462530, 12/10/09 16:28:50, V3465-04HD, 06:05:21:16, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_05_21_16.jpg, , ascend, reki, o, 20039807 _finframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/344/accesses.txt at bin\geopdvc.pl line 625 frameGrabs(): Can't read dir http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/vnta3469: Bad file descriptor at bin\geopdvc.pl line 625 dIndex(): Looking for epoch secs 1260462535 ... Found time 1260462534 after 4 iterations. >>> p = 1842: annotations(): 1842, 1260462535, 12/10/09 16:28:55, V3465-04HD, 06:05:26:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_05_26_17.jpg, , ascend, reki, o, 20039808 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.937 C", "S: 34.058", "O: 1.155 ml/l", "L: 85.02 %"] inline_name = vnta3469anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: object Ancillary data = ["object", "P: 259 db", "T: 7.913 C", "S: 34.062", "O: 1.159 ml/l", "L: 85.1 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 259.7 db", "T: 7.929 C", "S: 34.059", "O: 1.15 ml/l", "L: 84.74 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.932 C", "S: 34.059", "O: 1.151 ml/l", "L: 84.98 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.934 C", "S: 34.058", "O: 1.152 ml/l", "L: 85.04 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.937 C", "S: 34.058", "O: 1.155 ml/l", "L: 85.02 %"] inline_name = vnta3469anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: object Ancillary data = ["object", "P: 259 db", "T: 7.913 C", "S: 34.062", "O: 1.159 ml/l", "L: 85.1 %"] placeAnnotations(): Placing annotation 2: object Ancillary data = ["object", "P: 259 db", "T: 7.913 C", "S: 34.062", "O: 1.159 ml/l", "L: 85.1 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 259 db", "T: 7.913 C", "S: 34.062", "O: 1.159 ml/l", "L: 85.1 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 259.7 db", "T: 7.929 C", "S: 34.059", "O: 1.15 ml/l", "L: 84.74 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.929 C", "S: 34.059", "O: 1.15 ml/l", "L: 84.88 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.932 C", "S: 34.059", "O: 1.151 ml/l", "L: 84.98 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.934 C", "S: 34.058", "O: 1.152 ml/l", "L: 85.04 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.934 C", "S: 34.058", "O: 1.152 ml/l", "L: 85.04 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.934 C", "S: 34.058", "O: 1.152 ml/l", "L: 85.04 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 259.6 db", "T: 7.937 C", "S: 34.058", "O: 1.155 ml/l", "L: 85.02 %"] placeAnnotations(): Placing annotation 11: Ancillary data = ["", "P: 259.6 db", "T: 7.937 C", "S: 34.058", "O: 1.155 ml/l", "L: 85.02 %"] inline_name = vnta3469anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/vnta3469... 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/10/2009 4:00:08 PM' AND '12/10/2009 4:44:53 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2009/vnta/vnta3469.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4234,'/3Dreplay/3D.asp?dives=vnta3469','vnta3469','','','',3469 Executing sql: UPDATE Dive SET DiveLatMid = 36.7689, DiveLonMid = -121.8921, DiveDepthMid = -201.7 WHERE RovName = 'vnta' AND DiveNumber = 3469