Running bin\geopdvc.pl at Mon Feb 15 03:00:38 2016 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3895 bin\geopdvc.pl: Getting Expedition object for diveno = 3895 and rname = vnta set_expd(): rov = vnta, diveno = 3895 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 = 3895 AND RovName = 'vnta' RS->Fields(0)->Value= 5445 RS->Fields(1)->Value= 1450477828 RS->Fields(2)->Value= 1450478489 RS->Fields(3)->Value= 12/18/2015 10:30:28 PM RS->Fields(4)->Value= 12/18/2015 10:41:29 PM set_expd(): $self->{EndDDD} = 352 set_expd(): $self->{StartEsecs} = 1450476628 set_expd(): $self->{EndEsecs} = 1450479689 set_expd(): $self->{EndDtg} = 12/18/2015 10:41:29 PM _readLogr(): Reading videologr data from 2015352datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1450476628 & 1450479689 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1450479697, self->{EndEsecs} = 1450479689 _readLogr(): #LOGHOST_SYSTEM_UTC = 305 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1450476637 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1450479687 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1450476637 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1450479687 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1450476508, Refined EndEsecs = 1450479809 _readLogr(): Reading videologr data from 2015352datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1450476508 & 1450479809 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1450479817, self->{EndEsecs} = 1450479809 _readLogr(): #LOGHOST_SYSTEM_UTC = 329 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1450476517 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1450479807 bin\geopdvc.pl: expdid = 5445 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 = 352 (looping to 352) Does file exist?: \\atlas\RovNavEdit\2015\vnta\nav2015352vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2015\vnta\nav2015352vnta.txt esecs, east, north, lat, lon, head, pres 1450478185, 0, 0, 36.815603, -121.807650, 145.7, 17.34 rovNav(): 352: 659 in tList after reading from \\atlas\RovNavEdit\2015\vnta\nav2015352vnta.txt 659 in tList 659 in {self->{rovT}} 659 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1450478370 & 1450478235 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 16.982768727874 rovGDCListRefs(): dStats->sample_range = 15.6128859799305 rovGDCListRefs(): returning (1st elements): 1450477830 36.816523 -121.807900 12.2 2.68001667369411 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 352 (looping to 352) Does file exist?: \\atlas\RovNavEdit\2015\vnta\nav2015352vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2015\vnta\nav2015352vntaedited.txt esecs, east, north, laframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2015/352/accesses.txt at bin\geopdvc.pl line 2382 t, lon, head, pres 1450478185, 0, 0, 36.815598, -121.807627, 145.7, 17.34 rovNav(): 352: 659 in tList after reading from \\atlas\RovNavEdit\2015\vnta\nav2015352vntaedited.txt 659 in tList 659 in {self->{rovT}} 659 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1450478370 & 1450478235 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXX.........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 16.9827688406146 rovGDCListRefs(): dStats->sample_range = 15.6128858981093 rovGDCListRefs(): returning (1st elements): 1450477830 36.816590 -121.807844 12.2 2.68001665780031 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.81574100 hash{lon} = -121.80774000 latMin = 30.815741 latMax = 42.815741 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.815741 AND 42.815741 AND Longitude BETWEEN -127.80774 AND -115.80774 Dive lat = 36.81574100, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.80774000 & lat = 36.81574100 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3895rov_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 = geo2015/vnta/vnta3895lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 329 shipNav(): $#{$self->{shipLat}}[last] = 36.804000 Position subsampling set to 1 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.6258 0.5258 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.0328 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.7378 0.2622 0.0000 makeTracks(): n = 660 makeTracks(): Looping through 329 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3895tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2015/vnta/vnta3895mergedCtdNav.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 '12/18/2015 10:30:28 PM' AND '12/18/2015 10:41:29 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '12/18/2015 10:30:28 PM' AND '12/18/2015 10:41:29 PM') _findIndex(): Looking for epoch secs 1450477994 ... Found time 1450477994 after 1 iterations. >>> p = 164: annotations(): 164, 1450477994, 12/18/15 22:33:14, V3895-01HD, 00:00:13:03, water, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3895/00_00_13_03.jpg, 16.9599990844727, cruise, linda, u, 20059922 _findIndex(): Looking for epoch secs 1450478002 ... Found time 1450478004 after 5 iterations. >>> p = 174: annotations(): 174, 1450478002, 12/18/15 22:33:22, V3895-01HD, 00:00:20:25, water, , 17.7199993133545, cruise, linda, u, 4940009 _findIndex(): Looking for epoch secs 1450478004 ... Found time 1450478004 after 5 iterations. >>> p = 174: annotations(): 174, 1450478004, 12/18/15 22:33:24, V3895-01HD, 00:00:22:12, benthos, , 17.6000003814697, cruise, linda, u, 4939996 _findIndex(): Looking for epoch secs 1450478020 ... Found time 1450478024 after 5 iterations. >>> p = 194: annotations(): 194, 1450478020, 12/18/15 22:33:40, V3895-01HD, 00:00:38:02, water, , 17.5499992370605, cruise, linda, u, 4940010 _findIndex(): Looking for epoch secs 1450478038 ... Found time 1450478035 after 3 iterations. >>> p = 205: annotations(): 205, 1450478038, 12/18/15 22:33:58, V3895-01HD, 00:00:57:08, water, , 17.7199993133545, cruise, linda, u, 4940011 _findIndex(): Looking for epoch secs 1450478042 ... Found time 1450478045 after 5 iterations. >>> p = 215: annotations(): 215, 1450478042, 12/18/15 22:34:02, V3895-01HD, 00:01:00:16, sand, , 17.6800003051758, cruise, linda, u, 4940013 _findIndex(): Looking for epoch secs 1450478068 ... Found time 1450478065 after 5 iterations. >>> p = 235: annotations(): 235, 1450478068, 12/18/15 22:34:28, V3895-01HD, 00:01:26:18, water, , 17.6200008392334, cruise, linda, u, 4940003 _findIndex(): Looking for epoch secs 1450478060 ... Found time 1450478055 after 4 iterations. >>> p = 225: annotations(): 225, 1450478060, 12/18/15 22:34:20, V3895-01HD, 00:01:18:28, sand, , 17.6200008392334, cruise, linda, u, 4940014 _findIndex(): Looking for epoch secs 1450478074 ... Found time 1450478076 after 2 iterations. >>> p = 246: annotations(): 246, 1450478074, 12/18/15 22:34:34, V3895-01HD, 00:01:32:22, sand, , 17.3400001525879, cruise, linda, u, 4940015 _findIndex(): Looking for epoch secs 1450478098 ... Found time 1450478096 after 4 iterations. >>> p = 266: annotations(): 266, 1450478098, 12/18/15 22:34:58, V3895-01HD, 00:01:57:15, sand, , 17.3299999237061, cruise, linda, u, 4940016 _findIndex(): Looking for epoch secs 1450478126 ... Found time 1450478127 after 5 iterations. >>> p = 297: annotations(): 297, 1450478126, 12/18/15 22:35:26, V3895-01HD, 00:02:23:28, sand, , 17.5799999237061, cruise, linda, u, 4940018 _findIndex(): Looking for epoch secs 1450478156 ... Found time 1450478159 after 0 iterations. >>> p = 329: annotations(): 329, 1450478156, 12/18/15 22:35:56, V3895-01HD, 00:02:53:24, sand, , 17.1100006103516, cruise, linda, u, 4940020 _findIndex(): Looking for epoch secs 1450478174 ... Found time 1450478179 after 4 iterations. >>> p = 349: annotations(): 349, 1450478174, 12/18/15 22:36:14, V3895-01HD, 00:03:13:00, sand, , 17.4699993133545, cruise, linda, u, 4940021 _findIndex(): Looking for epoch secs 1450478084 ... Found time 1450478086 after 5 iterations. >>> p = 256: annotations(): 256, 1450478084, 12/18/15 22:34:44, V3895-01HD, 00:01:43:00, water, , 17.4699993133545, cruise, linda, u, 4940027 _findIndex(): Looking for epoch secs 1450478214 ... Found time 1450478210 after 5 iterations. >>> p = 380: annotations(): 380, 1450478214, 12/18/15 22:36:54, V3895-01HD, 00:03:52:16, sand, , 17.6000003814697, cruise, linda, u, 4940022 _findIndex(): Looking for epoch secs 1450478242 ... Found time 1450478241 after 2 iterations. >>> p = 411: annotations(): 411, 1450478242, 12/18/15 22:37:22, V3895-01HD, 00:04:21:13, sand, , 17.8700008392334, cruise, linda, u, 4940023 _findIndex(): Looking for epoch secs 1450478266 ... Found time 1450478261 after 4 iterations. >>> p = 431: annotations(): 431, 1450478266, 12/18/15 22:37:46, V3895-01HD, 00:04:45:13, sand, , 17.1499996185303, cruise, linda, u, 4940025 _findIndex(): Looking for epoch secs 1450478308 ... Found time 1450478303 after 4 iterations. >>> p = 473: annotations(): 473, 1450478308, 12/18/15 22:38:28, V3895-01HD, 00:05:27:05, sand, , 18.0599994659424, cruise, linda, u, 4940026 _findIndex(): Looking for epoch secs 1450478308 ... Found time 1450478303 after 4 iterations. >>> p = 473: annotations(): 473, 1450478308, 12/18/15 22:38:28, V3895-01HD, 00:05:27:05, water, , 18.0599994659424, cruise, linda, u, 4940028 _findIndex(): Looking for epoch secs 1450478188 ... Found time 1450478189 after 5 iterations. >>> p = 359: annotations(): 359, 1450478188, 12/18/15 22:36:28, V3895-01HD, 00:03:26:20, benthos, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3895/00_03_26_20.jpg, 16.7900009155273, cruise, linda, u, 20059923 _findIndex(): Looking for epoch secs 1450478272 ... Found time 1450478271 after 5 iterations. >>> p = 441: annotations(): 441, 1450478272, 12/18/15 22:37:52, V3895-01HD, 00:04:50:04, benthos, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3895/00_04_50_04.jpg, 17.5100002288818, cruise, linda, u, 20059924 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: sand Ancillary data = ["sand", "P: 19.7 db", "T: 13.703 C", "S: 33.337", "O: 5.519 ml/l", "L: 64.59 %"] placeAnnotations(): Placing annotation 20: benthos Ancillary data = ["benthos", "P: 19.3 db", "T: 13.687 C", "S: 33.339", "O: 5.485 ml/l", "L: 66.72 %"] inline_name = vnta3895anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: benthos Ancillary data = ["benthos", "P: 19.8 db", "T: 13.712 C", "S: 33.337", "O: 5.53 ml/l", "L: 66.84 %"] placeAnnotations(): Placing annotation 4: water Ancillary data = ["water", "P: 19.9 db", "T: 13.705 C", "S: 33.337", "O: 5.52 ml/l", "L: 66.17 %"] placeAnnotations(): Placing annotation 6: water Ancillary data = ["water", "P: 19.8 db", "T: 13.715 C", "S: 33.339", "O: 5.532 ml/l", "L: 68.27 %"] placeAnnotations(): Placing annotation 8: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.719 C", "S: 33.339", "O: 5.532 ml/l", "L: 67.42 %"] placeAnnotations(): Placing annotation 10: sand Ancillary data = ["sand", "P: 19.7 db", "T: 13.703 C", "S: 33.337", "O: 5.519 ml/l", "L: 64.59 %"] placeAnnotations(): Placing annotation 12: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.699 C", "S: 33.336", "O: 5.516 ml/l", "L: 66.32 %"] placeAnnotations(): Placing annotation 14: sand Ancillary data = ["sand", "P: 20 db", "T: 13.701 C", "S: 33.336", "O: 5.515 ml/l", "L: 65.45 %"] placeAnnotations(): Placing annotation 16: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.7 C", "S: 33.338", "O: 5.512 ml/l", "L: 66.48 %"] placeAnnotations(): Placing annotation 18: water Ancillary data = ["water", "P: 20.2 db", "T: 13.689 C", "S: 33.336", "O: 5.506 ml/l", "L: 66.13 %"] placeAnnotations(): Placing annotation 20: benthos Ancillary data = ["benthos", "P: 19.3 db", "T: 13.687 C", "S: 33.339", "O: 5.485 ml/l", "L: 66.72 %"] inline_name = vnta3895anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: water Ancillary data = ["water", "P: 19.5 db", "T: 13.72 C", "S: 33.338", "O: 5.539 ml/l", "L: 68.24 %"] placeAnnotations(): Placing annotation 2: benthos Ancillary data = ["benthos", "P: 19.8 db", "T: 13.712 C", "S: 33.337", "O: 5.53 ml/l", "L: 66.84 %"] placeAnnotations(): Placing annotation 3: water Ancillary data = ["water", "P: 19.8 db", "T: 13.712 C", "S: 33.337", "O: 5.53 ml/l", "L: 66.84 %"] placeAnnotations(): Placing annotation 4: water Ancillary data = ["water", "P: 19.9 db", "T: 13.705 C", "S: 33.337", "O: 5.52 ml/l", "L: 66.17 %"] placeAnnotations()frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2015/352/accesses.txt at bin\geopdvc.pl line 625 : Placing annotation 5: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.715 C", "S: 33.339", "O: 5.532 ml/l", "L: 68.27 %"] placeAnnotations(): Placing annotation 6: water Ancillary data = ["water", "P: 19.8 db", "T: 13.715 C", "S: 33.339", "O: 5.532 ml/l", "L: 68.27 %"] placeAnnotations(): Placing annotation 7: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.723 C", "S: 33.337", "O: 5.533 ml/l", "L: 66.43 %"] placeAnnotations(): Placing annotation 8: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.719 C", "S: 33.339", "O: 5.532 ml/l", "L: 67.42 %"] placeAnnotations(): Placing annotation 9: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.723 C", "S: 33.337", "O: 5.533 ml/l", "L: 66.43 %"] placeAnnotations(): Placing annotation 10: sand Ancillary data = ["sand", "P: 19.7 db", "T: 13.703 C", "S: 33.337", "O: 5.519 ml/l", "L: 64.59 %"] placeAnnotations(): Placing annotation 11: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.693 C", "S: 33.337", "O: 5.5 ml/l", "L: 63.72 %"] placeAnnotations(): Placing annotation 12: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.699 C", "S: 33.336", "O: 5.516 ml/l", "L: 66.32 %"] placeAnnotations(): Placing annotation 13: water Ancillary data = ["water", "P: 19.8 db", "T: 13.669 C", "S: 33.337", "O: 5.479 ml/l", "L: 64.02 %"] placeAnnotations(): Placing annotation 14: sand Ancillary data = ["sand", "P: 20 db", "T: 13.701 C", "S: 33.336", "O: 5.515 ml/l", "L: 65.45 %"] placeAnnotations(): Placing annotation 15: sand Ancillary data = ["sand", "P: 19.4 db", "T: 13.696 C", "S: 33.342", "O: 5.492 ml/l", "L: 67.12 %"] placeAnnotations(): Placing annotation 16: sand Ancillary data = ["sand", "P: 19.8 db", "T: 13.7 C", "S: 33.338", "O: 5.512 ml/l", "L: 66.48 %"] placeAnnotations(): Placing annotation 17: sand Ancillary data = ["sand", "P: 19.3 db", "T: 13.697 C", "S: 33.337", "O: 5.511 ml/l", "L: 66.9 %"] placeAnnotations(): Placing annotation 18: water Ancillary data = ["water", "P: 20.2 db", "T: 13.689 C", "S: 33.336", "O: 5.506 ml/l", "L: 66.13 %"] placeAnnotations(): Placing annotation 19: benthos Ancillary data = ["benthos", "P: 20.2 db", "T: 13.689 C", "S: 33.336", "O: 5.506 ml/l", "L: 66.13 %"] placeAnnotations(): Placing annotation 20: benthos Ancillary data = ["benthos", "P: 19.3 db", "T: 13.687 C", "S: 33.339", "O: 5.485 ml/l", "L: 66.72 %"] placeAnnotations(): Placing annotation 21: Ancillary data = ["", "P: 19.3 db", "T: 13.697 C", "S: 33.337", "O: 5.511 ml/l", "L: 66.9 %"] inline_name = vnta3895anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2015/vnta3895... frameGrabs(): matched 00_00_13_03 in