Running bin\geopdvc.pl at Sat Aug 23 03:00:04 2014 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3787 bin\geopdvc.pl: Getting Expedition object for diveno = 3787 and rname = vnta set_expd(): rov = vnta, diveno = 3787 set_expd(): Executing sql: SELECT ExpeditionID_FK, DateDiff(ss, '01/01/70', DiveStartDtg), DateDiff(ss, '01/01/70', DiveEndDtg), DiveStartDtg, DiveEndDtg FROM Dive WHERE DiveNumber = 3787 AND RovName = 'vnta' RS->Fields(0)->Value= 5032 RS->Fields(1)->Value= 1403712436 RS->Fields(2)->Value= 1403714046 RS->Fields(3)->Value= 6/25/2014 4:07:16 PM RS->Fields(4)->Value= 6/25/2014 4:34:06 PM set_expd(): $self->{EndDDD} = 176 set_expd(): $self->{StartEsecs} = 1403711236 set_expd(): $self->{EndEsecs} = 1403715246 set_expd(): $self->{EndDtg} = 6/25/2014 4:34:06 PM _readLogr(): Reading videologr data from 2014176datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1403711236 & 1403715246 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1403715253, self->{EndEsecs} = 1403715246 _readLogr(): #LOGHOST_SYSTEM_UTC = 400 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1403711242 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1403715243 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1403711242 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1403715243 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1403711116, Refined EndEsecs = 1403715366 _readLogr(): Reading videologr data from 2014176datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1403711116 & 1403715366 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1403715373, self->{EndEsecs} = 1403715366 _readLogr(): #LOGHOST_SYSTEM_UTC = 424 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1403711122 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1403715363 bin\geopdvc.pl: expdid = 5032 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 = 176 (looping to 176) Does file exist?: \\atlas\RovNavEdit\2014\vnta\nav2014176vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2014\vnta\nav2014176vnta.txt esecs, east, north, lat, lon, head, pres 1403712546, 0, 0, 36.752073, -121.838897, 106.0, 28.06 1403713047, 0, 0, 36.752147, -121.838660, 101.6, 33.03 1403713548, 0, 0, 36.752122, -121.838672, 100.8, 33.03 1403714049, 0, 0, 36.752008, -121.838687, 45.0, 32.85 1403714611, 0, 0, 36.751942, -121.838687, 7.2, 32.18 1403715112, 0, 0, 36.752120, -121.838643, 8.0, 33.27 rovNav(): 176: 3253 in tList after reading from \\atlas\RovNavEdit\2014\vnta\nav2014176vnta.txt 3253 in tList 3253 in {self->{rovT}} 3253 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1403712975 & 1403713350 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 32.6046556983165 rovGDCListRefs(): dStats->sample_range = 30.7285272232317 rovGDCListRefs(): returning (1st elements): 1403711636 36.710862 -122.183112 350.3 2.54107746735444 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 176 (looping to 176) Does file exist?: \\atlas\RovNavEdit\2014\vnta\nav2014176vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2014\vnta\nav2014176vntaedited.txt esecs, east, north, lat, lon, head, pres 1403712546, 0, 0, 36.752072, -121.838883, 106.0, 28.06 1403713047, 0, 0, 36.752142, -121.838668, 101.6, 33.03 1403713548, 0, 0, 36.752122, -121.838674, 100.8, 33.03 1403714049, 0, 0, 36.752005, -121.838688, 45.0, 32.85 1403714611, 0, 0, 36.751946, -121.838670, 7.2, 32.18 1403715112, 0, 0, 36.752120, -121.838656, 8.0, 33.27 rovNav(): 176: 3253 in tList after reading from \\atlas\RovNavEdit\2014\vnta\nav2014176vntaedited.txt 3253 in tList 3253 in {self->{rovT}} 3253 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1403712975 & 1403713350 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXX..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 32.6046557689758 rovGDCListRefs(): dStats->sample_range = 30.7285362858198 rovGDCListRefs(): returning (1st elements): 1403711636 36.752073 -121.838897 350.3 2.54106820625576 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.75208900 hash{lon} = -121.83868100 latMin = 30.752089 latMax = 42.752089 Executing sql: SELECT Area, Latitude, Longitude FROM frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2014/176/accesses.txt at bin\geopdvc.pl line 2382 GeoOrigin WHERE Latitude BETWEEN 30.752089 AND 42.752089 AND Longitude BETWEEN -127.838681 AND -115.838681 Dive lat = 36.75208900, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.83868100 & lat = 36.75208900 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3787rov_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 = geo2014/vnta/vnta3787lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 424 shipNav(): $#{$self->{shipLat}}[last] = 36.751392 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... varColors = SalColors index0 = 1 1 .1 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0 0 1 makeTracks(): n = 3254 makeTracks(): Looping through 424 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3787tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2014/vnta/vnta3787mergedCtdNav.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 = '') AND (RecordedDate BETWEEN '6/25/2014 4:07:16 PM' AND '6/25/2014 4:34:06 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '6/25/2014 4:07:16 PM' AND '6/25/2014 4:34:06 PM') _findIndex(): Looking for epoch secs 1403712908 ... Found time 1403712911 after 5 iterations. >>> p = 863: annotations(): 863, 1403712908, 06/25/14 16:15:08, V3787-01HD, 00:10:59:24, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_10_59_24.jpg, 31.4699993133545, descend, whpa, u, 20055590 _findIndex(): Looking for epoch secs 1403712912 ... Found time 1403712911 after 5 iterations. >>> p = 863: annotations(): 863, 1403712912, 06/25/14 16:15:12, V3787-01HD, 00:11:03:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_11_03_11.jpg, 31.5699996948242, descend, whpa, u, 20055591 _findIndex(): Looking for epoch secs 1403712916 ... Found time 1403712911 after 5 iterations. >>> p = 863: annotations(): 863, 1403712916, 06/25/14 16:15:16, V3787-01HD, 00:11:07:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_11_07_11.jpg, 31.7199993133545, descend, whpa, u, 20055592 _findIndex(): Looking for epoch secs 1403712955 ... Found time 1403712955 after 8 iterations. >>> p = 907: annotations(): 907, 1403712955, 06/25/14 16:15:55, V3787-01HD, 00:11:45:25, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_11_45_25.jpg, 31.7600002288818, descend, whpa, u, 20055593 _findIndex(): Looking for epoch secs 1403713021 ... Found time 1403frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2014/176/accesses.txt at bin\geopdvc.pl line 625 713025 after 7 iterations. >>> p = 977: annotations(): 977, 1403713021, 06/25/14 16:17:01, V3787-01HD, 00:12:52:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_12_52_10.jpg, 32.1100006103516, descend, whpa, u, 20055594 _findIndex(): Looking for epoch secs 1403713368 ... Found time 1403713369 after 4 iterations. >>> p = 1320: annotations(): 1320, 1403713368, 06/25/14 16:22:48, V3787-01HD, 00:18:39:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_18_39_02.jpg, 32.2900009155273, descend, whpa, u, 20055595 _findIndex(): Looking for epoch secs 1403713370 ... Found time 1403713369 after 4 iterations. >>> p = 1320: annotations(): 1320, 1403713370, 06/25/14 16:22:50, V3787-01HD, 00:18:41:18, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_18_41_18.jpg, 32.2799987792969, descend, whpa, u, 20055596 _findIndex(): Looking for epoch secs 1403713757 ... Found time 1403713756 after 8 iterations. >>> p = 1707: annotations(): 1707, 1403713757, 06/25/14 16:29:17, V3787-01HD, 00:25:08:28, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3787/00_25_08_28.jpg, 32.6300010681152, descend, whpa, u, 20055597 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta3787anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 31.1 db", "T: 10.086 C", "S: 33.843", "O: 2.567 ml/l", "L: 82.35 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 31.3 db", "T: 10.094 C", "S: 33.838", "O: 2.612 ml/l", "L: 81.11 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 32 db", "T: 10.085 C", "S: 33.852", "O: 2.451 ml/l", "L: 79.87 %"] placeAnnotations(): Placing annotation 8: Ancillary data = ["", "P: 31.7 db", "T: 10.051 C", "S: 33.853", "O: 2.447 ml/l", "L: 79.29 %"] inline_name = vnta3787anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 31.1 db", "T: 10.086 C", "S: 33.843", "O: 2.567 ml/l", "L: 82.35 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 31.1 db", "T: 10.086 C", "S: 33.843", "O: 2.567 ml/l", "L: 82.35 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 31.1 db", "T: 10.086 C", "S: 33.843", "O: 2.567 ml/l", "L: 82.35 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 31.3 db", "T: 10.094 C", "S: 33.838", "O: 2.612 ml/l", "L: 81.11 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 31.4 db", "T: 10.107 C", "S: 33.833", "O: 2.676 ml/l", "L: 82.49 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 32 db", "T: 10.085 C", "S: 33.852", "O: 2.451 ml/l", "L: 79.87 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 32 db", "T: 10.085 C", "S: 33.852", "O: 2.451 ml/l", "L: 79.87 %"] placeAnnotations(): Placing annotation 8: Ancillary data = ["", "P: 31.7 db", "T: 10.051 C", "S: 33.853", "O: 2.447 ml/l", "L: 79.29 %"] inline_name = vnta3787anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2014/vnta3787... frameGrabs(): matched 00_10_59_24 in