Running bin\geopdvc.pl at Thu Jun 1 03:00:40 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4017 bin\geopdvc.pl: Getting Expedition object for diveno = 4017 and rname = vnta set_expd(): rov = vnta, diveno = 4017 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 = 4017 AND RovName = 'vnta' RS->Fields(0)->Value= 5894 RS->Fields(1)->Value= 1491252000 RS->Fields(2)->Value= 1491255900 RS->Fields(3)->Value= 4/3/2017 8:40:00 PM RS->Fields(4)->Value= 4/3/2017 9:45:00 PM set_expd(): $self->{EndDDD} = 093 set_expd(): $self->{StartEsecs} = 1491250800 set_expd(): $self->{EndEsecs} = 1491257100 set_expd(): $self->{EndDtg} = 4/3/2017 9:45:00 PM _readLogr(): Reading videologr data from 2017093datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491250800 & 1491257100 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491257101, self->{EndEsecs} = 1491257100 _readLogr(): #LOGHOST_SYSTEM_UTC = 628 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491250810 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491257091 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1491250810 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1491257091 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1491250680, Refined EndEsecs = 1491257220 _readLogr(): Reading videologr data from 2017093datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491250680 & 1491257220 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491257221, self->{EndEsecs} = 1491257220 _readLogr(): #LOGHOST_SYSTEM_UTC = 652 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491250690 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491257211 bin\geopdvc.pl: expdid = 5894 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 = 093 (looping to 093) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017093vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017093vnta.txt esecs, east, north, lat, lon, head, pres 1491252269, 0, 0, 36.688437, -122.047500, 30.4, 41.91 1491252770, 0, 0, 36.693958, -122.049513, 140.3, 201.64 1491253271, 0, 0, 36.693215, -122.048127, 139.6, 201.65 1491253772, 0, 0, 36.692682, -122.047800, 269.0, 201.53 1491254273, 0, 0, 36.692835, -122.049428, 269.9, 201.73 1491254773, 0, 0, 36.693337, -122.050215, 321.5, 54.77 1491255274, 0, 0, 36.692670, -122.051210, 331.9, 9.73 rovNav(): 093: 3432 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017093vnta.txt 3432 in tList 3432 in {self->{rovT}} 3432 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are & rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): rovGDCListRefs(): dMedian = 199.675938772127 rovGDCListRefs(): dStats->sample_range = 198.314932548769 rovGDCListRefs(): returning (1st elements): 1491250715 36.688437 -122.047500 322.5 2.52123042119463 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 093 (looping to 093) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017093vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017093vntaedited.txt esecs, east, north, lat, lon, head, pres 1491252269, 0, 0, 36.694122, -122.049982, 30.4, 41.91 1491252770, 0, 0, 36.693927, -122.049495, 140.3, 201.64 1491253271, 0, 0, 36.693195, -122.048113, 139.6, 201.65 1491253772frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/093/accesses.txt at bin\geopdvc.pl line 2382 , 0, 0, 36.692671, -122.047777, 269.0, 201.53 1491254273, 0, 0, 36.692816, -122.049458, 269.9, 201.73 1491254773, 0, 0, 36.693348, -122.050230, 321.5, 54.77 1491255274, 0, 0, 36.692692, -122.051219, 331.9, 9.73 rovNav(): 093: 3432 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017093vntaedited.txt 3432 in tList 3432 in {self->{rovT}} 3432 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are & rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): rovGDCListRefs(): dMedian = 199.675939319318 rovGDCListRefs(): dStats->sample_range = 198.314933201327 rovGDCListRefs(): returning (1st elements): 1491250715 36.689228 -122.047845 322.5 2.52123024490461 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.69299000 hash{lon} = -122.04938900 latMin = 30.69299 latMax = 42.69299 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.69299 AND 42.69299 AND Longitude BETWEEN -128.049389 AND -116.049389 Dive lat = 36.69299000, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.04938900 & lat = 36.69299000 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4017rov_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 = geo2017/vnta/vnta4017lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 652 shipNav(): $#{$self->{shipLat}}[last] = 36.710896 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 = 3433 makeTracks(): Looping through 652 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4017tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4017mergedCtdNav.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 '4/3/2017 8:40:00 PM' AND '4/3/2017 9:45:00 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '4/3/2017 8:40:00 PM' AND '4/3/2017 9:45:00 PM') _findIndex(): Looking for epoch secs 1491252716 ... Found time 1491252711 after 5 iterations. >>> p = 804: annotations(): 804, 1491252716, 04/03/17 20:51:56, V4017-01HD, 00:12:02:04, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4017/00_12_02_04.jpg, 200.100006103516, descend, svonthun, u, 20066505 _findIndex(): Looking for epoch secs 1491254351 ... FoundframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/093/accesses.txt at bin\geopdvc.pl line 625 time 1491254349 after 6 iterations. >>> p = 2439: annotations(): 2439, 1491254351, 04/03/17 21:19:11, V4017-01HD, 00:39:15:14, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4017/00_39_15_14.jpg, 199.800003051758, descend, svonthun, u, 20066506 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4017anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: Ancillary data = ["", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] inline_name = vnta4017anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: equipment Ancillary data = ["equipment", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] placeAnnotations(): Placing annotation 2: Ancillary data = ["", "P: db", "T: C", "S: ", "O: ml/l", "L: %"] inline_name = vnta4017anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4017... frameGrabs(): matched 00_12_02_04 in [IMG]00_12_02_04.jpg 2017-04-11 00:37 275K  frameGrabs(): matched 00_39_15_14 in [IMG]00_39_15_14.jpg 2017-04-11 00:37 275K  iList = 00_12_02_04.jpg 00_39_15_14.jpg _findIndex(): Looking for epoch secs 1491252716 ... Found time 1491252711 after 5 iterations. >>> p = 804: frameGrabs(): index = 804 EXPD::frameGrabs(): 804: Time=, Depth=, Lat=36.694005, Lon=-122.049660, Pres=, Temp=, Sal=, O2=, Light= _findIndex(): Looking for epoch secs 1491254351 ... Found time 1491254349 after 6 iterations. >>> p = 2439: frameGrabs(): index = 2439 EXPD::frameGrabs(): 2439: Time=, Depth=, Lat=36.692823, Lon=-122.049683, Pres=, Temp=, Sal=, O2=, Light= bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 2 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_12_02_04.jpg 1491252716 fw= 0.0 cm placeImages(): indx = 804, geoPosition = 36.694005, -122.049660, -200.15192256281, rovDepth = 200.15192256281 inline_name = vnta4017fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_12_02_04.jpg 1491252716 fw= 0.0 cm placeImages(): indx = 804, geoPosition = 36.694005, -122.049660, -200.15192256281, rovDepth = 200.15192256281 inline_name = vnta4017fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_12_02_04.jpg 1491252716 fw= 0.0 cm placeImages(): indx = 804, geoPosition = 36.694005, -122.049660, -200.15192256281, rovDepth = 200.15192256281 placeImages(): Placing image 1 00_39_15_14.jpg 1491254351 fw= 0.0 cm placeImages(): indx = 2439, geoPosition = 36.692823, -122.049683, -199.715620839966, rovDepth = 199.715620839966 inline_name = vnta4017fg01.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 '4/3/2017 8:40:00 PM' AND '4/3/2017 9:45:00 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4017.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5894,'/3Dreplay/3D.asp?dives=vnta4017','vnta4017','','','',4017 Executing sql: UPDATE Dive SET DiveLatMid = 36.6930, DiveLonMid = -122.0494, DiveDepthMid = -199.7 WHERE RovName = 'vnta' AND DiveNumber = 4017