Running bin\geopdvc.pl at Sat Jan 16 03:00:11 2010 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3454 bin\geopdvc.pl: Getting Expedition object for diveno = 3454 and rname = vnta set_expd(): rov = vnta, diveno = 3454 set_expd(): $self->{EndDDD} = 322 set_expd(): $self->{StartEsecs} = 1258562827 set_expd(): $self->{EndEsecs} = 1258568106 set_expd(): $self->{EndDtg} = 11/18/2009 5:55:06 PM _readLogr(): Reading videologr data from 2009322datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1258562827 & 1258568106 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1258568114, self->{EndEsecs} = 1258568106 _readLogr(): #LOGHOST_SYSTEM_UTC = 527 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1258562833 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1258568104 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1258562833 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1258568104 refineStartAndEndEsecs(): setting StartEsecs to 1258562827 refineStartAndEndEsecs(): setting EndEsecs to 1258562843 refineStartAndEndEsecs(): numMinBack = 21.7333333333333, numMinForward = 2.96666666666667 refineStartAndEndEsecs(): Refined StartEsecs = 1258562723, Refined EndEsecs = 1258567084 _readLogr(): Reading videologr data from 2009322datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1258562723 & 1258567084 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1258567094, self->{EndEsecs} = 1258567084 _readLogr(): #LOGHOST_SYSTEM_UTC = 435 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1258562733 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1258567084 bin\geopdvc.pl: expdid = 4217 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 = 322 (looping to 322) Does file exist?: \\tornado\TempNav\2009\vnta\nav2009322vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2009\vnta\nav2009322vnta.txt esecs, east, north, lat, lon, head, pres 1258564614, 0, 0, 36.748820, -122.106465, 115.5, 182.63 1258565115, 0, 0, 36.748457, -122.105132, 133.9, 282.56 1258565616, 0, 0, 36.748710, -122.106340, 284.2, 226.70 1258566117, 0, 0, 36.749290, -122.106642, 94.4, 57.49 1258566618, 0, 0, 36.749780, -122.108625, 86.5, 8.09 rovNav(): 322: 2557 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009322vnta.txt 2557 in tList 2557 in {self->{rovT}} 2557 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1258565160 & 1258565115 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...............XX.....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XX rovGDCListRefs(): dMedian = 155.011628465575 rovGDCListRefs(): dStats->sample_range = 279.285289435909 rovGDCListRefs(): returning (1st elements): 1258564033 36.703448 -122.046938 107.5 2.07455526704562 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 322 (looping to 322) Does file exist?: \\tornado\TempNav\2009\vnta\nav2009322vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\tornado\TempNav\2009\vnta\nav2009322vntaedited.txt esecs, east, north, lat, lon, head, pres 1258564614, 0, 0, 36.748872, -122.106510, 115.5, 182.63 1258565115, 0, 0, 36.748359, -122.104943, 133.9, 282.56 1258565616, 0, 0, 36.748668, -122.105336, 284.2, 226.70 1258566117, 0, 0, 36.749257, -122.106629, 94.4, 57.49 1258566618, 0, 0, 36.749960, -122.108838, 86.5, 8.09 rovNav(): 322: 2557 in tList after reading from \\tornado\TempNav\2009\vnta\nav2009322vntaedited.txt rovNav(): Initial # seconds to fill at beginning of dive = 1310 starting at StartEsecs = 1258562723 rovNav(): Initial # seconds to fill at end of dive = 184 ending at EndEsecs =1258567084 rovNav(): LOGHOST_SYSTEM_UTC[0] = 1258562733 rovNav(): $LOGHOST_SYSTEM_UTC[end] = 1258567084 rovNav(): numMinBack = 21.5666666666667, numMinForward = 2.96666666666667 rovNav(): Refined StartEsecs = 1258562723, Refined EndEsecs = 1258567084 rovNav(): Refined # seconds to fill at beginning of dive = 1310 starting at StartEsecs = 1258562723 rovNav(): Refined # seconds to fill at end of dive = 184 ending at EndEsecs =1258567084 rovNav(): num tList = 2557 rovNav(): num eList = 2557 rovNav(): Adding data to begining of dive from logr indices 0 .. 130 rovNav(): Adding data to end of dive from logr indices 417 .. 435 rovNav(): num in tList now = 2707 rovNav(): num in eList now = 2707 2707 in tList 2707 in {self->{rovT}} 2707 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1258565160 & 1258565115 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXX....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/322/accesses.txt at bin\geopdvc.pl line 2382 ................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XX rovGDCListRefs(): dMedian = 141.149972725398 rovGDCListRefs(): dStats->sample_range = 286.290373609031 rovGDCListRefs(): returning (1st elements): 1258562733 36.749917 -122.106107 275.1 -5 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.74885900 hash{lon} = -122.10630700 latMin = 30.748859 latMax = 42.748859 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.748859 AND 42.748859 AND Longitude BETWEEN -128.106307 AND -116.106307 Dive lat = 36.74885900, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.10630700 & lat = 36.74885900 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3454rov_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/vnta3454lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = ptlo shipNav(): $#{$self->{shipLat}} = 435 shipNav(): $#{$self->{shipLat}}[last] = 36.750083 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 = 2708 makeTracks(): Looping through 435 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3454tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2009/vnta/vnta3454mergedCtdNav.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 '11/18/2009 5:07:07 PM' AND '11/18/2009 5:55:06 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '11/18/2009 5:07:07 PM' AND '11/18/2009 5:55:06 PM') _findIndex(): Looking for epoch secs 1258565186 ... Found time 1258565189 after 6 iterations. >>> p = 1204: annotations(): 1204, 1258565186, 11/18/09 17:26:26, V3454-01HD, 00:19:50:09, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3454/00_19_50_09.jpg, 278.799987792969, descend, reki, o, 20038980 _findIndex(): Looking for epoch secs 1258565213 ... Found time 1258565210 after 5 iterations. >>> p = 1225: annotations(): 1225, 1258565213, 11/18/09 17:26:53, V3454-01HD, 00:20:18:09, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3454/00_20_18_09.jpg, 278.890014648438, descend, reki, o, 20038981 _findIndex(): Looking for epoch secs 1258565289 ... Found time 1258565284 after 7 iterations. >>> p = 1299: annotations(): 1299, 1258565289, 11/18/09 17:28:09, V3454-01HD, 00:21:33:21, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3454/00_21_33_21.jpg, 279.029998779297, descend, reki, o, 20038982 _findIndex(): Looking for epoch secs 1258565345 ... Found time 1258565349 after 7 iterations. >>> p = 1363: annotations(): 1363, 1258565345, 11/18/09 17:29:05, V3454-01HD, 00:22:29:26, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3454/00_22_29_26.jpg, 279.570007324219, descend, reki, o, 20038983 _findIndex(): Looking for epoch secs 1258565414 ... Found time 1258565412 after 7 iterations. >>> p = 1426: annotations(): 1426, 1258565414, 11/18/09 17:30:14, V3454-01HD, 00:23:39:00, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3454/00_23_39_00.jpg, 280.089996337891, descend, reki, o, 20038984 _findIndex(): Looking for epoch secs 1258565428 ... Found time 1258565423 after 4 iterations. >>> p = 1437: annotations(): 1437, 1258565428, 11/18/09 17:30:28, V3454-01HD, 00:23:52:15, Midwater Respirometry System, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3454/00_23_52_15.jpg, 279.380004882813, descend, reki, o, 20038985 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta3454anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 283.4 db", "T: 8.075 C", "S: 34.07", "O: 1.28 ml/l", "L: 87.86 %"] placeAnnotations(): Placing annotation 4: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 283.8 db", "T: 8.052 C", "S: 34.071", "O: 1.263 ml/l", "L: 87.84 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 283.8 db", "T: 8.055 C", "S: 34.072", "O: 1.255 ml/l", "L: 87.77 %"] inline_name = vnta3454anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 283.2 db", "T: 8.086 C", "S: 34.07", "O: 1.282 ml/l", "L: 87.86 %"] placeAnnotations(): Placing annotation 2: Midwater ReframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/322/accesses.txt at bin\geopdvc.pl line 625 spirometry System Ancillary data = ["Midwater Respirometry System", "P: 283.4 db", "T: 8.075 C", "S: 34.07", "O: 1.28 ml/l", "L: 87.86 %"] placeAnnotations(): Placing annotation 3: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 283.2 db", "T: 8.061 C", "S: 34.07", "O: 1.277 ml/l", "L: 87.85 %"] placeAnnotations(): Placing annotation 4: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 283.8 db", "T: 8.052 C", "S: 34.071", "O: 1.263 ml/l", "L: 87.84 %"] placeAnnotations(): Placing annotation 5: Midwater Respirometry System Ancillary data = ["Midwater Respirometry System", "P: 284.2 db", "T: 8.051 C", "S: 34.071", "O: 1.256 ml/l", "L: 87.83 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 283.8 db", "T: 8.055 C", "S: 34.072", "O: 1.255 ml/l", "L: 87.77 %"] inline_name = vnta3454anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2009/vnta3454... frameGrabs(): matched 00_19_50_09 in [IMG] 00_19_50_09.jpg 20-Nov-2009 00:10 62k frameGrabs(): matched 00_20_18_09 in [IMG] 00_20_18_09.jpg 20-Nov-2009 00:10 79k frameGrabs(): matched 00_21_33_21 in [IMG] 00_21_33_21.jpg 20-Nov-2009 00:10 73k frameGrabs(): matched 00_22_29_26 in [IMG] 00_22_29_26.jpg 20-Nov-2009 00:10 64k frameGrabs(): matched 00_23_39_00 in [IMG] 00_23_39_00.jpg 20-Nov-2009 00:10 76k frameGrabs(): matched 00_23_52_15 in [IMG] 00_23_52_15.jpg 20-Nov-2009 00:10 81k iList = 00_19_50_09.jpg 00_20_18_09.jpg 00_21_33_21.jpg 00_22_29_26.jpg 00_23_39_00.jpg 00_23_52_15.jpg _findIndex(): Looking for epoch secs 1258565186 ... Found time 1258565189 after 6 iterations. >>> p = 1204: frameGrabs(): index = 1204 EXPD::frameGrabs(): 1204: Time=1258565190, Depth=280.92, Lat= 36.748278, Lon=-122.104786, Pres=283.2, Temp=8.086, Sal=34.07, O2=1.282, Light=87.86 _findIndex(): Looking for epoch secs 1258565213 ... Found time 1258565210 after 5 iterations. >>> p = 1225: frameGrabs(): index = 1225 EXPD::frameGrabs(): 1225: Time=1258565205, Depth=281.12, Lat= 36.748255, Lon=-122.104741, Pres=283.4, Temp=8.075, Sal=34.07, O2=1.28, Light=87.86 _findIndex(): Looking for epoch secs 1258565289 ... Found time 1258565284 after 7 iterations. >>> p = 1299: frameGrabs(): index = 1299 EXPD::frameGrabs(): 1299: Time=1258565280, Depth=280.92, Lat=36.748359, Lon=-122.104783, Pres=283.2, Temp=8.061, Sal=34.07, O2=1.277, Light=87.85 _findIndex(): Looking for epoch secs 1258565345 ... Found time 1258565349 after 7 iterations. >>> p = 1363: frameGrabs(): index = 1363 EXPD::frameGrabs(): 1363: Time=1258565355, Depth=281.51, Lat= 36.748333, Lon=-122.104733, Pres=283.8, Temp=8.052, Sal=34.071, O2=1.263, Light=87.84 _findIndex(): Looking for epoch secs 1258565414 ... Found time 1258565412 after 7 iterations. >>> p = 1426: frameGrabs(): index = 1426 EXPD::frameGrabs(): 1426: Time=1258565415, Depth=281.91, Lat= 36.748370, Lon=-122.104720, Pres=284.2, Temp=8.051, Sal=34.071, O2=1.256, Light=87.83 _findIndex(): Looking for epoch secs 1258565428 ... Found time 1258565423 after 4 iterations. >>> p = 1437: frameGrabs(): index = 1437 EXPD::frameGrabs(): 1437: Time=1258565430, Depth=281.51, Lat=36.748366, Lon=-122.104733, Pres=283.8, Temp=8.055, Sal=34.072, O2=1.255, Light=87.77 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 6 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_19_50_09.jpg 1258565186 fw= 0.0 cm XplaceImages(): indx = 1204, geoPosition = 36.748278, -122.104786, -279.149276397209, rovDepth = 279.149276397209 inline_name = vnta3454fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_19_50_09.jpg 1258565186 fw= 0.0 cm XplaceImages(): indx = 1204, geoPosition = 36.748278, -122.104786, -279.149276397209, rovDepth = 279.149276397209 placeImages(): Placing image 2 00_21_33_21.jpg 1258565289 fw= 0.0 cm XplaceImages(): indx = 1299, geoPosition = 36.748359, -122.104783, -279.020411448261, rovDepth = 279.020411448261 placeImages(): Placing image 4 00_23_39_00.jpg 1258565414 fw= 0.0 cm XplaceImages(): indx = 1426, geoPosition = 36.748370, -122.104720, -280.21982471593, rovDepth = 280.21982471593 inline_name = vnta3454fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_19_50_09.jpg 1258565186 fw= 0.0 cm XplaceImages(): indx = 1204, geoPosition = 36.748278, -122.104786, -279.149276397209, rovDepth = 279.149276397209 placeImages(): Placing image 1 00_20_18_09.jpg 1258565213 fw= 0.0 cm XplaceImages(): indx = 1225, geoPosition = 36.748255, -122.104741, -279.288052359125, rovDepth = 279.288052359125 placeImages(): Placing image 2 00_21_33_21.jpg 1258565289 fw= 0.0 cm XplaceImages(): indx = 1299, geoPosition = 36.748359, -122.104783, -279.020411448261, rovDepth = 279.020411448261 placeImages(): Placing image 3 00_22_29_26.jpg 1258565345 fw= 0.0 cm XplaceImages(): indx = 1363, geoPosition = 36.748333, -122.104733, -279.169100101147, rovDepth = 279.169100101147 placeImages(): Placing image 4 00_23_39_00.jpg 1258565414 fw= 0.0 cm XplaceImages(): indx = 1426, geoPosition = 36.748370, -122.104720, -280.21982471593, rovDepth = 280.21982471593 placeImages(): Placing image 5 00_23_52_15.jpg 1258565428 fw= 0.0 cm .placeImages(): indx = 1437, geoPosition = 36.748366, -122.104733, -279.585425141473, rovDepth = 279.585425141473 inline_name = vnta3454fg01.wrl 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 '11/18/2009 5:07:07 PM' AND '11/18/2009 5:55:06 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2009/vnta/vnta3454.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',4217,'/3Dreplay/3D.asp?dives=vnta3454','vnta3454','','','',3454 Executing sql: UPDATE Dive SET DiveLatMid = 36.7489, DiveLonMid = -122.1063, DiveDepthMid = -141.2 WHERE RovName = 'vnta' AND DiveNumber = 3454