Running bin\geopdvc.pl at Sun Feb 7 03:01:35 2010 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3470 bin\geopdvc.pl: Getting Expedition object for diveno = 3470 and rname = vnta set_expd(): rov = vnta, diveno = 3470 set_expd(): $self->{EndDDD} = 344 set_expd(): $self->{StartEsecs} = 1260463499 set_expd(): $self->{EndEsecs} = 1260468717 set_expd(): $self->{EndDtg} = 12/10/2009 5:51:57 PM _readLogr(): Reading videologr data from 2009344datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1260463499 & 1260468717 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1260468721, self->{EndEsecs} = 1260468717 _readLogr(): #LOGHOST_SYSTEM_UTC = 521 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1260463500 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1260468711 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1260463500 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1260468711 refineStartAndEndEsecs(): setting StartEsecs to 1260463499 refineStartAndEndEsecs(): setting EndEsecs to 1260463520 refineStartAndEndEsecs(): numMinBack = 21.65, numMinForward = 17.5666666666667 refineStartAndEndEsecs(): Refined StartEsecs = 1260463400, Refined EndEsecs = 1260468571 _readLogr(): Reading videologr data from 2009344datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1260463400 & 1260468571 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1260468581, self->{EndEsecs} = 1260468571 _readLogr(): #LOGHOST_SYSTEM_UTC = 516 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1260463410 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1260468571 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 1260465132, 0, 0, 36.768733, -121.890770, 292.7, 95.55 1260465633, 0, 0, 36.769007, -121.892098, 159.1, 250.95 1260466134, 0, 0, 36.769052, -121.892117, 149.9, 258.42 1260466635, 0, 0, 36.769193, -121.892203, 169.2, 259.32 1260467136, 0, 0, 36.769233, -121.891047, 83.7, 81.58 rovNav(): 344: 2759 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009344vnta.txt 2759 in tList 2759 in {self->{rovT}} 2759 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1260466695 & 1260465885 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X rovGDCListRefs(): dMedian = 197.716114964144 rovGDCListRefs(): dStats->sample_range = 255.424229290568 rovGDCListRefs(): returning (1st elements): 1260463400 36.769192 -121.892098 143.2 9.35016612710686 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 1260465132, 0, 0, 36.768734, -121.890767, 292.7, 95.55 1260465633, 0, 0, 36.768986, -121.892105, 159.1, 250.95 1260466134, 0, 0, 36.769052, -121.892119, 149.9, 258.42 1260466635, 0, 0, 36.769200, -121.892200, 169.2, 259.32 1260467136, 0, 0, 36.769239, -121.891047, 83.7, 81.58 rovNav(): 344: 2759 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009344vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 0 starting at StartEsecs = 1260463400 rovNav(): Initial # seconds to fill at end of dive = 1088 ending at EndEsecs =1260468571 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1260463410 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1260468571 rovNav(): numMinBack = 21.4833333333333, numMinForward = 17.5666666666667 rovNav(): Refined StartEsecs = 1260463400, Refined EndEsecs = 1260468571 rovNav(): Refined # seconds to fill at beginning of dive = 0 starting at StartEsecs = 1260463400 rovNav(): Refined # seconds to fill at end of dive = 1088 ending at EndEsecs =1260468571 rovNav(): num tList = 2759 rovNav(): num eList = 2759 rovNav(): Adding data to begining of dive from logr indices 0 .. 0 rovNav(): Adding data to end of dive from logr indices 408 .. 516 rovNav(): num in tList now = 2869 rovNav(): num in eList now = 2869 2869 in tList 2869 in {self->{rovT}} 2869 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1260466695 & 1260465885 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXX...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................X rovGDCListRefs(): dMedian = 189.743120775806 rovGDCListRefs(): dStats->sample_range = 262.429290578901 rovGDCListRefs(): returning (1st elements): 1260463410 36.770498 -121.891957 144.1 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.76909200 hash{lon} = -121.89210400 latMin = 30.769092 latMax = 42.769092 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.769092 AND 42.769092 AND Longitude BETWEEN -127.892104 AND -115.892104 Dive lat = 36.76909200, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.89210400 & lat = 36.76909200 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3470rov_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/vnta3470lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 516 shipNav(): $#{$self->{shipLat}}[last] = 36.763848 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 = SalColorsframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/344/accesses.txt at bin\geopdvc.pl line 2382 index0 = 1 1 .1 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0 0 1 makeTracks(): n = 2870 makeTracks(): Looping through 516 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3470tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2009/vnta/vnta3470mergedCtdNav.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 5:04:59 PM' AND '12/10/2009 5:51:57 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '12/10/2009 5:04:59 PM' AND '12/10/2009 5:51:57 PM') _findIndex(): Looking for epoch secs 1260465854 ... Found time 1260465850 after 7 iterations. >>> p = 1130: annotations(): 1130, 1260465854, 12/10/09 17:24:14, V3465-04HD, 06:07:17:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_17_02.jpg, , ascend, reki, o, 20039809 _findIndex(): Looking for epoch secs 1260465854 ... Found time 1260465850 after 7 iterations. >>> p = 1130: annotations(): 1130, 1260465854, 12/10/09 17:24:14, V3465-04HD, 06:07:17:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_17_02.jpg, , ascend, reki, o, 20039810 _findIndex(): Looking for epoch secs 1260465854 ... Found time 1260465850 after 7 iterations. >>> p = 1130: annotations(): 1130, 1260465854, 12/10/09 17:24:14, V3465-04HD, 06:07:17:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_17_02.jpg, , ascend, reki, o, 20039811 _findIndex(): Looking for epoch secs 1260465854 ... Found time 1260465850 after 7 iterations. >>> p = 1130: annotations(): 1130, 1260465854, 12/10/09 17:24:14, V3465-04HD, 06:07:17:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_17_02.jpg, , ascend, reki, o, 20039812 _findIndex(): Looking for epoch secs 1260465854 ... Found time 1260465850 after 7 iterations. >>> p = 1130: annotations(): 1130, 1260465854, 12/10/09 17:24:14, V3465-04HD, 06:07:17:02, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_17_02.jpg, , ascend, reki, o, 20039813 _findIndex(): Looking for epoch secs 1260465915 ... Found time 1260465917 after 7 iterations. >>> p = 1197: annotations(): 1197, 1260465915, 12/10/09 17:25:15, V3465-04HD, 06:07:18:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_18_11.jpg, , ascend, reki, o, 20039814 _findIndex(): Looking for epoch secs 1260465917 ... Found time 1260465917 after 7 iterations. >>> p = 1197: annotations(): 1197, 1260465917, 12/10/09 17:25:17, V3465-04HD, 06:07:20:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_20_11.jpg, , ascend, reki, o, 20039815 _findIndex(): Looking for epoch secs 1260465919 ... Found time 1260465917 after 7 iterations. >>> p = 1197: annotations(): 1197, 1260465919, 12/10/09 17:25:19, V3465-04HD, 06:07:22:26, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_22_26.jpg, , ascend, reki, o, 20039816 _findIndex(): Looking for epoch secs 1260465922 ... Found time 1260465917 after 7 iterations. >>> p = 1197: annotations(): 1197, 1260465922, 12/10/09 17:25:22, V3465-04HD, 06:07:25:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_07_25_11.jpg, , ascend, reki, o, 20039817 _findIndex(): Looking for epoch secs 1260466052 ... Found time 1260466052 after 7 iterations. >>> p = 1332: annotations(): 1332, 1260466052, 12/10/09 17:27:32, V3465-04HD, 06:09:35:05, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_09_35_05.jpg, , ascend, reki, o, 20039818 _findIndex(): Looking for epoch secs 1260466054 ... Found time 1260466052 after 7 iterations. >>> p = 1332: annotations(): 1332, 1260466054, 12/10/09 17:27:34, V3465-04HD, 06:09:37:05, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_09_37_05.jpg, , ascend, reki, o, 20039819 _findIndex(): Looking for epoch secs 1260466055 ... Found time 1260466052 after 7 iterations. >>> p = 1332: annotations(): 1332, 1260466055, 12/10/09 17:27:35, V3465-04HD, 06:09:38:20, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_09_38_20.jpg, , ascend, reki, o, 20039820 _findIndex(): Looking for epoch secs 1260466156 ... Found time 1260466154 after 0 iterations. >>> p = 1434: annotations(): 1434, 1260466156, 12/10/09 17:29:16, V3465-04HD, 06:11:19:15, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3465/06_11_19_15.jpg, , ascend, reki, o, 20039821 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.072 C", "S: 34.039", "O: 1.215 ml/l", "L: 82.87 %"] inline_name = vnta3470anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.058 C", "S: 34.041", "O: 1.206 ml/l", "L: 83.74 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.058 C", "S: 34.041", "O: 1.206 ml/l", "L: 83.74 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 260.8 db", "T: 8.075 C", "S: 34.038", "O: 1.216 ml/l", "L: 83.77 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 260.8 db", "T: 8.075 C", "S: 34.038", "O: 1.216 ml/l", "L: 83.77 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.072 C", "S: 34.039", "O: 1.215 ml/l", "L: 82.87 %"] placeAnnotations(): Placing annotation 12: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.072 C", "S: 34.039", "O: 1.215 ml/l", "L: 82.87 %"] inline_name = vnta3470anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.058 C", "S: 34.041", "O: 1.206 ml/l", "L: 83.74 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.058 C", "S: 34.041", "O: 1.206 ml/l", "L: 83.74 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.058 C", "S: 34.041", "O: 1.206 ml/l", "L: 83.74 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.058 C", "S: 34.041", "O: 1.206 ml/l", "L: 83.74 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.058 C", "S: 34.041", "O: 1.206 ml/l", "L: 83.74 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 260.8 db", "T: 8.075 C", "S: 34.038", "O: 1.216 ml/l", "L: 83.77 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 260.8 db", "T: 8.075 C", "S: 34.038", "O: 1.216 ml/l", "L: 83.77 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 260.8 db", "T: 8.075 C", "S: 34.038", "O: 1.216 ml/l", "L: 83.77 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data frameGrabs(): 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/vnta3470: Bad file descriptor at bin\geopdvc.pl line 625 = ["physical object", "P: 260.8 db", "T: 8.075 C", "S: 34.038", "O: 1.216 ml/l", "L: 83.77 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.072 C", "S: 34.039", "O: 1.215 ml/l", "L: 82.87 %"] placeAnnotations(): Placing annotation 11: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.072 C", "S: 34.039", "O: 1.215 ml/l", "L: 82.87 %"] placeAnnotations(): Placing annotation 12: physical object Ancillary data = ["physical object", "P: 260.7 db", "T: 8.072 C", "S: 34.039", "O: 1.215 ml/l", "L: 82.87 %"] placeAnnotations(): Placing annotation 13: Ancillary data = ["", "P: 260.6 db", "T: 8.091 C", "S: 34.036", "O: 1.222 ml/l", "L: 83.82 %"] inline_name = vnta3470anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/vnta3470... 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 5:04:59 PM' AND '12/10/2009 5:51:57 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2009/vnta/vnta3470.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4234,'/3Dreplay/3D.asp?dives=vnta3470','vnta3470','','','',3470 Executing sql: UPDATE Dive SET DiveLatMid = 36.7691, DiveLonMid = -121.8921, DiveDepthMid = -189.7 WHERE RovName = 'vnta' AND DiveNumber = 3470