Running bin\geopdvc.pl at Sun Nov 24 03:01:07 2013 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3739 bin\geopdvc.pl: Getting Expedition object for diveno = 3739 and rname = vnta set_expd(): rov = vnta, diveno = 3739 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 = 3739 AND RovName = 'vnta' RS->Fields(0)->Value= 4858 RS->Fields(1)->Value= 1380231744 RS->Fields(2)->Value= 1380232997 RS->Fields(3)->Value= 9/26/2013 9:42:24 PM RS->Fields(4)->Value= 9/26/2013 10:03:17 PM set_expd(): $self->{EndDDD} = 269 set_expd(): $self->{StartEsecs} = 1380230544 set_expd(): $self->{EndEsecs} = 1380234197 set_expd(): $self->{EndDtg} = 9/26/2013 10:03:17 PM _readLogr(): Reading videologr data from 2013269datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1380230544 & 1380234197 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1380234207, self->{EndEsecs} = 1380234197 _readLogr(): #LOGHOST_SYSTEM_UTC = 365 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1380230546 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1380234197 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1380230546 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1380234197 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1380230424, Refined EndEsecs = 1380234317 _readLogr(): Reading videologr data from 2013269datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1380230424 & 1380234317 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1380234327, self->{EndEsecs} = 1380234317 _readLogr(): #LOGHOST_SYSTEM_UTC = 389 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1380230426 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1380234317 bin\geopdvc.pl: expdid = 4858 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 = 269 (looping to 269) Does file exist?: \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2013\vnta\nav2013269vnta.txt esecs, east, north, lat, lon, head, pres 1380231620, 0, 0, 36.787132, -122.057063, 322.4, 3.85 1380232121, 0, 0, 36.752188, -121.838242, 226.7, 31.48 1380232622, 0, 0, 36.752070, -121.838182, 255.3, 32.88 1380233123, 0, 0, 36.753155, -121.839135, 301.2, 3.91 rovNav(): 269: 1753 in tList after reading from \\atlas\RovNavEdit\2013\vnta\nav2013269vnta.txt 1753 in tList 1753 in {self->{rovT}} 1753 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1380232665 & 1380232695 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 27.0617349977075 rovGDCListRefs(): dStats->sample_range = 30.4407216330855 rovGDCListRefs(): returning (1st elements): 1380231585 36.787132 -122.057063 14.4 2.86861657029972 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 269 (looping to 269) Does file exist?: \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt esecs, east, north, lat, lon, head, pres 1380231620, 0, 0, 36.787132, -122.057063, 322.4, 3.85 1380232121, 0, 0, 36.752188, -121.838242, 226.7, 31.48 1380232622, 0, 0, 36.752070, -121.838182, 255.3, 32.88 1380233123, 0, 0, 36.753155, -121.839135, 301.2, 3.91 rovNav(): 269: 1753 in tList after reading from \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt 1753 in tList 1753 in {self->{rovT}} 1753 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1380232665 & 1380232695 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXX............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 27.0617349977075 rovGDCListRefs(): dStats->sample_range = 30.4407216330855 rovGDCListRefs(): returning (1st elements): 1380231585 36.787132 -122.057063 14.4 2.86861657029972 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.75268200 hash{lon} = -121.83836300 latMin = 30.752682 latMax = 42.752682 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.752682 AND 42.752682 AND Longitude BETWEEN -127.838363 AND -115.838363 Dive lat = 36.75268200, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.83836300 & lat = 36.75268200 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3739rov_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 frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2013/269/accesses.txt at bin\geopdvc.pl line 2382 val = 15, color = 1 0 0 World = geo2013/vnta/vnta3739lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 389 shipNav(): $#{$self->{shipLat}}[last] = 36.778167 Position subsampling set to 2 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 = 1754 makeTracks(): Looping through 389 point of ship position data... makeTracks(): posSubsample = 2 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3739tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2013/vnta/vnta3739mergedCtdNav.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 '9/26/2013 9:42:24 PM' AND '9/26/2013 10:03:17 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '9/26/2013 9:42:24 PM' AND '9/26/2013 10:03:17 PM') _findIndex(): Looking for epoch secs 1380232143 ... Found time 1380232146 after 6 iterations. >>> p = 560: annotations(): 560, 1380232143, 09/26/13 21:49:03, V3739-01HD, 07:59:05:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/07_59_05_17.jpg, 31.2600002288818, descend, ventana, u, 20052239 _findIndex(): Looking for epoch secs 1380232146 ... Found time 1380232146 after 6 iterations. >>> p = 560: annotations(): 560, 1380232146, 09/26/13 21:49:06, V3739-01HD, 07:59:09:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/07_59_09_02.jpg, 30.8500003814697, descend, ventana, u, 20052240 _findIndex(): Looking for epoch secs 1380232148 ... Found time 1380232146 after 6 iterations. >>> p = 560: annotations(): 560, 1380232148, 09/26/13 21:49:08, V3739-01HD, 07:59:10:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/07_59_10_17.jpg, 30.8400001525879, descend, ventana, u, 20052241 _findIndex(): Looking for epoch secs 1380232151 ... Found time 1380232146 after 6 iterations. >>> p = 560: annotations(): 560, 1380232151, 09/26/13 21:49:11, V3739-01HD, 07:59:13:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/07_59_13_02.jpg, 31.1499996185303, descend, ventana, u, 20052242 _findIndex(): Looking for epoch secs 1380232153 ... Found time 1380232153 after 7 iterations. >>> p = 567: annotations(): 567, 1380232153, 09/26/13 21:49:13, V3739-01HD, 07:59:15:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/07_59_15_17.jpg, 31.2600002288818, descend, ventana, u, 20052243 _findIndex(): Looking for epoch secs 1380232155 ... Found time 1380232160 after 5 iterations. >>> p = 574: annotations(): 574, 1380232155, 09/26/13 21:49:15, V3739-01HD, 07:59:17:16, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/07_59_17_16.jpg, 31.2000007629395, descend, ventana, u, 20052244 _findIndex(): Looking for epoch secs 1380232550 ... Found time 1380232551 after 7 iterations. >>> p = 964: annotations(): 964, 1380232550, 09/26/13 21:55:50, V3739-01HD, 08:05:52:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/08_05_52_04.jpg, 32.689998626709, descend, ventana, u, 20052245 _findIndex(): LooframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2013/269/accesses.txt at bin\geopdvc.pl line 625 king for epoch secs 1380232556 ... Found time 1380232558 after 6 iterations. >>> p = 971: annotations(): 971, 1380232556, 09/26/13 21:55:56, V3739-01HD, 08:05:58:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/08_05_58_04.jpg, 32.4599990844727, descend, ventana, u, 20052246 _findIndex(): Looking for epoch secs 1380232558 ... Found time 1380232558 after 6 iterations. >>> p = 971: annotations(): 971, 1380232558, 09/26/13 21:55:58, V3739-01HD, 08:06:00:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/08_06_00_04.jpg, 32.5, descend, ventana, u, 20052247 _findIndex(): Looking for epoch secs 1380232562 ... Found time 1380232558 after 6 iterations. >>> p = 971: annotations(): 971, 1380232562, 09/26/13 21:56:02, V3739-01HD, 08:06:04:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3739/08_06_04_04.jpg, 32.4000015258789, descend, ventana, u, 20052248 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 28.7 db", "T: 13.085 C", "S: 33.704", "O: 4.524 ml/l", "L: 65.36 %"] inline_name = vnta3739anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 27.2 db", "T: 13.132 C", "S: 33.704", "O: 4.572 ml/l", "L: 73.67 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 27.2 db", "T: 13.132 C", "S: 33.704", "O: 4.572 ml/l", "L: 73.67 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 27.3 db", "T: 13.133 C", "S: 33.704", "O: 4.569 ml/l", "L: 73.23 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 28.7 db", "T: 13.085 C", "S: 33.704", "O: 4.524 ml/l", "L: 65.36 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 28.7 db", "T: 13.085 C", "S: 33.704", "O: 4.524 ml/l", "L: 65.36 %"] inline_name = vnta3739anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 27.2 db", "T: 13.132 C", "S: 33.704", "O: 4.572 ml/l", "L: 73.67 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 27.2 db", "T: 13.132 C", "S: 33.704", "O: 4.572 ml/l", "L: 73.67 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 27.2 db", "T: 13.132 C", "S: 33.704", "O: 4.572 ml/l", "L: 73.67 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 27.2 db", "T: 13.132 C", "S: 33.704", "O: 4.572 ml/l", "L: 73.67 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 27.3 db", "T: 13.133 C", "S: 33.704", "O: 4.569 ml/l", "L: 73.23 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 27.3 db", "T: 13.133 C", "S: 33.704", "O: 4.569 ml/l", "L: 73.23 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 28.7 db", "T: 13.073 C", "S: 33.706", "O: 4.514 ml/l", "L: 66.65 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 28.7 db", "T: 13.085 C", "S: 33.704", "O: 4.524 ml/l", "L: 65.36 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 28.7 db", "T: 13.085 C", "S: 33.704", "O: 4.524 ml/l", "L: 65.36 %"] placeAnnotations(): Placing annotation 10: Ancillary data = ["", "P: 28.7 db", "T: 13.085 C", "S: 33.704", "O: 4.524 ml/l", "L: 65.36 %"] inline_name = vnta3739anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2013/vnta3739... frameGrabs(): matched 07_59_05_17 in