Running bin\geopdvc.pl at Tue Jan 25 15:45:13 2011 on host EPHYRA-VM Command line: bin\geopdvc.pl -rov vnta -dive 3479 bin\geopdvc.pl: Getting Expedition object for diveno = 3479 and rname = vnta set_expd(): rov = vnta, diveno = 3479 set_expd(): $self->{EndDDD} = 005 set_expd(): $self->{StartEsecs} = 1262717100 set_expd(): $self->{EndEsecs} = 1262721420 set_expd(): $self->{EndDtg} = 1/5/2010 7:37:00 PM _readLogr(): Reading videologr data from 2010005datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1262717100 & 1262721420 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1262721421, self->{EndEsecs} = 1262721420 _readLogr(): #LOGHOST_SYSTEM_UTC = 430 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1262717110 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1262721411 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1262717110 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1262721411 refineStartAndEndEsecs(): setting StartEsecs to 1262717100 refineStartAndEndEsecs(): setting EndEsecs to 1262717140 refineStartAndEndEsecs(): numMinBack = 21.3333333333333, numMinForward = 9.5 refineStartAndEndEsecs(): Refined StartEsecs = 1262717020, Refined EndEsecs = 1262720790 _readLogr(): Reading videologr data from 2010005datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1262717020 & 1262720790 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1262720800, self->{EndEsecs} = 1262720790 _readLogr(): #LOGHOST_SYSTEM_UTC = 376 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1262717030 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1262720790 bin\geopdvc.pl: expdid = 4252 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 = 005 (looping to 005) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010005vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010005vnta.txt esecs, east, north, lat, lon, head, pres 1262717306, 0, 0, 36.768595, -121.891547, 60.5, 201.41 1262718463, 0, 0, 36.767962, -121.891957, 170.8, 61.57 1262718964, 0, 0, 36.768145, -121.892220, 303.9, 249.73 1262719465, 0, 0, 36.768233, -121.892350, 320.7, 255.65 1262719966, 0, 0, 36.768318, -121.891843, 302.9, 59.45 rovNav(): 005: 2383 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010005vnta.txt 2383 in tList 2383 in {self->{rovT}} 2383 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1262718855 & 1262718825 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .....................................................XXXXXXXX rovGDCListRefs(): dMedian = 208.856970657716 rovGDCListRefs(): dStats->sample_range = 251.607293507153 rovGDCListRefs(): returning (1st elements): 1262717020 36.768508 -121.892043 261.3 230.145349287404 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 005 (looping to 005) Does file exist?: \\tornado\TempNav\2010\vnta\nav2010005vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2010\vnta\nav2010005vntaedited.txt esecs, east, north, lat, lon, head, pres 1262717306, 0, 0, 36.768588, -121.891562, 60.5, 201.41 1262718463, 0, 0, 36.767973, -121.891957, 170.8, 61.57 1262718964, 0, 0, 36.768188, -121.892215, 303.9, 249.73 1262719465, 0, 0, 36.768231, -121.892343, 320.7, 255.65 1262719966, 0, 0, 36.768308, -121.891854, 302.9, 59.45 rovNav(): 005: 2383 in tList after reading from \\tornado\TempNav\2010\vnta\nav2010005vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 0 starting at StartEsecs = 1262717020 rovNframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/005/accesses.txt at bin\geopdvc.pl line 2382 av(): Initial # seconds to fill at end of dive = 726 ending at EndEsecs =1262720790 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1262717030 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1262720790 rovNav(): numMinBack = 21.1666666666667, numMinForward = 9.5 rovNav(): Refined StartEsecs = 1262717020, Refined EndEsecs = 1262720790 rovNav(): Refined # seconds to fill at beginning of dive = 0 starting at StartEsecs = 1262717020 rovNav(): Refined # seconds to fill at end of dive = 726 ending at EndEsecs =1262720790 rovNav(): num tList = 2383 rovNav(): num eList = 2383 rovNav(): Adding data to begining of dive from logr indices 0 .. 0 rovNav(): Adding data to end of dive from logr indices 304 .. 376 rovNav(): num in tList now = 2457 rovNav(): num in eList now = 2457 2457 in tList 2457 in {self->{rovT}} 2457 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1262718855 & 1262718825 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .....................................................XXXXXXXX rovGDCListRefs(): dMedian = 202.768503120143 rovGDCListRefs(): dStats->sample_range = 258.771171283504 rovGDCListRefs(): returning (1st elements): 1262717030 36.766890 -121.894170 231.2 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.76825500 hash{lon} = -121.89198750 latMin = 30.768255 latMax = 42.768255 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.768255 AND 42.768255 AND Longitude BETWEEN -127.8919875 AND -115.8919875 Dive lat = 36.76825500, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.89198750 & lat = 36.76825500 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3479rov_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 = geo2010/vnta/vnta3479lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 376 shipNav(): $#{$self->{shipLat}}[last] = 36.770713 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 = 2458 makeTracks(): Looping through 376 point of ship position data... makeTracks(): posSubsample = 2 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3479tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2010/vnta/vnta3479mergedCtdNav.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 '1/5/2010 7:05:00 PM' AND '1/5/2010 7:37:00 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '1/5/2010 7:05:00 PM' AND '1/5/2010 7:37:00 PM') _findIndex(): Looking for epoch secs 1262719077 ... Found time 1262719078 after 6 iterations. >>> p = 1400: annotations(): 1400, 1262719077, 01/05/10 19:17:57, V3479-01HD, 10:26:52:19, mud, , 253.410003662109, stationary, linda, o, 3181189 _findIndex(): Looking for epoch secs 1262719083 ... Found time 1262719078 after 6 iterations. >>> p = 1400: annotations(): 1400, 1262719083, 01/05/10 19:18:03, V3479-01HD, 10:26:58:23, Merluccius productus, , 253.470001220703, stationary, linda, o, 3181190 _findIndex(): Looking for epoch secs 1262719088 ... Found time 1262719087 after 7 iterations. >>> p = 1409: annotations(): 1409, 1262719088, 01/05/10 19:18:08, V3479-01HD, 10:27:03:15, equipment, , 253.429992675781, stationary, linda, o, 3181191 _findIndex(): Looking for epoch secs 1262719088 ... Found time 1262719087 after 7 iterations. >>> p = 1409: annotations(): 1409, 1262719088, 01/05/10 19:18:08, V3479-01HD, 10:31:08:22, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3479/10_31_08_22.jpg, 253.429992675781, stationary, linda, o, 3181196 _findIndex(): Looking for epoch secs 1262719088 ... Found time 1262719087 after 7 iterations. >>> p = 1409: annotations(): 1409, 1262719088, 01/05/10 19:18:08, V3479-01HD, 10:31:43:15, Sergestes, , 253.429992675781, stationary, linda, o, 3181197 _findIndex(): Looking for epoch secs 1262719088 ... Found time 1262719087 after 7 iterations. >>> p = 1409: annotations(): 1409, 1262719088, 01/05/10 19:18:08, V3479-01HD, 10:32:19:14, Raja rhina, , 253.429992675781, stationary, linda, o, 3181199 _findIndex(): Looking for epoch secs 1262719175 ... Found time 1262719174 after 5 iterations. >>> p = 1496: annotations(): 1496, 1262719175, 01/05/10 19:19:35, V3479-01, 10:28:29:03, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3479/10_28_29_03.jpg, 253.470001220703, descend, whpa, o, 20039927 _findIndex(): Looking for epoch secs 1262719264 ... Found time 1262719261 after 7 iterations. >>> p = 1582: annotations(): 1582, 1262719264, 01/05/10 19:21:04, V3479-01HD, 10:29:57:29, mud, , 253.490005493164, stationary, linda, o, 3181194 _findIndex(): Looking for epoch secs 1262719282 ... Found time 1262719280 after 7 iterations. >>> p = 1601: annotations(): 1601, 1262719282, 01/05/10 19:21:22, V3479-01HD, 10:30:16:27, Merluccius productus, , 253.490005493164, stationary, linda, o, 3181195 _findIndex(): Looking for epoch secs 1262719322 ... Found time 1262719318 after 7 iterations. >>> p = 1639: annotations(): 1639, 1262719322, 01/05/10 19:22:02, V3479-01, 10:30:56:15, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3479/10_30_56_15.jpg, 253.5, descend, whpa, o, 20039928 _findIndex(): Looking for epoch secs 1262719325 ... Found time 1262719328 after 5 iterations. >>> p = 1649: annotations(): 1649, 1262719325, 01/05/10 19:22:05, V3479-01, 10:30:58:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3479/10_30_58_17.jpg, 253.5, descend, whpa, o, 20039929 _findIndex(): Looking for epoch secs 1262719438 ... Found time 1262719434 after 7 iterations. >>> p = 1755: annotations(): 1755, 1262719438, 01/05/10 19:23:58, V3479-01HD, 10:32:52:08, equipment, , 253.139999389648, cruise, linda, o, 3181200 _findIndex(): Looking for epoch secs 1262719440 ... Found time 1262719444 after 4 iterations. >>> p = 1765: annotations(): 1765, 1262719440, 01/05/10 19:24:00, V3479-01HD, 10:32:54:23, Squalus suckleyi, , 253.160003662109, cruise, linda, o, 3181201 _findIndex(): Looking for epoch secs 1262719440 ... Found time 1262719444 after 4 iterations. >>> p = 1765: annotations(): 1765, 1262719440, 01/05/10 19:24:00, V3479-01HD, 10:33:16:17, Raja rhina, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3479/10_33_16_17.jpg, 253.160003662109, cruise, linda, o, 3181203 _findIndex(): Looking for epoch secs 1262719440 ... Found time 1262719444 after 4 iterations. >>> p = 1765: annotations(): 1765, 1262719440, 01/05/10 19:24:00, V3479-01HD, 10:34:16:01, Raja rhina, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3479/10_34_16_01.jpg, 253.160003662109, cruise, linda, o, 3181206 _findIndex(): Looking for epoch secs 1262719491 ... Found time 1262719491 after 7 iterations. >>> p = 1812: annotations(): 1812, 1262719491, 01/05/10 19:24:51, V3479-01HD, 10:33:45:22, Raja rhina, , 253.100006103516, cruise, linda, o, 3181204 _findIndex(): Looking for epoch secs 1262719641 ... Found time 1262719644 after 7 iterations. >>> p = 1965: annotations(): 1965, 1262719641, 01/05/10 19:27:21, V3479-01HD, 10:36:15:03, water, , 225.330001831055, ascend, linda, o, 3181207 _findIndex(): Looking for epoch secs 1262719641 ... Found time 1262719644 after 7 iterations. >>> p = 1965: annotations(): 1965, 1262719641, 01/05/10 19:27:21, V3479-01HD, 10:39:42:12, water, , 225.330001831055, ascend, linda, o, 3181214 _findIndex(): Looking for epoch secs 1262720078 ... Found time 1262720080 after 9 iterations. >>> p = 2386: annotations(): 2386, 1262720078, 01/05/10 19:34:38, V3479-01HD, 10:43:32:07, water, , , ascend, linda, o, 3181218 _findIndex(): Looking for epoch secs 1262720100 ... Found time 1262720100 after 11 iterations. >>> p = 2388: annotations(): 2388, 1262720100, 01/05/10 19:35:00, V3479-01HD, 10:43:54:07, surface water, , , ascend, linda, o, 3181222 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 20: Ancillary data = ["", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] inline_name = vnta3479anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: equipment Ancillary data = ["equipment", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 4: Sergestes Ancillary data = ["Sergestes", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 8: Merluccius productus Ancillary data = ["Merluccius productus", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 12: Squalus suckleyi Ancillary data = ["Squalus suckleyi", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 14: Raja rhina Ancillary data = ["Raja rhina", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 16: water Ancillary data = ["water", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 18: water Ancillary data = ["water", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 20: Ancillary data = ["", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] inline_name = vnta3479anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: Merluccius productus Ancillary data = ["Merluccius productus", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 2: equipment Ancillary data = ["equipment", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 3: equipment Ancillary data = ["equipment", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 4: Sergestes Ancillary data = ["Sergestes", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 5: Raja rhina Ancillary data = ["Raja rhina", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: db", "T: C", "frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/005/accesses.txt at bin\geopdvc.pl line 625 S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 7: mud Ancillary data = ["mud", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 8: Merluccius productus Ancillary data = ["Merluccius productus", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 11: equipment Ancillary data = ["equipment", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 12: Squalus suckleyi Ancillary data = ["Squalus suckleyi", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 13: Raja rhina Ancillary data = ["Raja rhina", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 14: Raja rhina Ancillary data = ["Raja rhina", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 15: Raja rhina Ancillary data = ["Raja rhina", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 16: water Ancillary data = ["water", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 17: water Ancillary data = ["water", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 18: water Ancillary data = ["water", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 19: surface water Ancillary data = ["surface water", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 20: Ancillary data = ["", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] inline_name = vnta3479anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2010/vnta3479... frameGrabs(): matched 10_28_29_03 in