Running bin\geopdvc.pl at Sat Jun 3 03:00:04 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4023 bin\geopdvc.pl: Getting Expedition object for diveno = 4023 and rname = vnta set_expd(): rov = vnta, diveno = 4023 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 = 4023 AND RovName = 'vnta' RS->Fields(0)->Value= 5908 RS->Fields(1)->Value= 1491407760 RS->Fields(2)->Value= 1491410670 RS->Fields(3)->Value= 4/5/2017 3:56:00 PM RS->Fields(4)->Value= 4/5/2017 4:44:30 PM set_expd(): $self->{EndDDD} = 095 set_expd(): $self->{StartEsecs} = 1491406560 set_expd(): $self->{EndEsecs} = 1491411870 set_expd(): $self->{EndDtg} = 4/5/2017 4:44:30 PM _readLogr(): Reading videologr data from 2017095datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491406560 & 1491411870 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491411877, self->{EndEsecs} = 1491411870 _readLogr(): #LOGHOST_SYSTEM_UTC = 530 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491406566 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491411867 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1491406566 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1491411867 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1491406440, Refined EndEsecs = 1491411990 _readLogr(): Reading videologr data from 2017095datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1491406440 & 1491411990 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1491411997, self->{EndEsecs} = 1491411990 _readLogr(): #LOGHOST_SYSTEM_UTC = 554 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1491406446 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1491411987 bin\geopdvc.pl: expdid = 5908 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 = 095 (looping to 095) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017095vnta.txt esecs, east, north, lat, lon, head, pres 1491408251, 0, 0, 36.699627, -122.049453, 137.7, 100.97 1491408752, 0, 0, 36.698775, -122.048662, 137.7, 100.50 1491409252, 0, 0, 36.698520, -122.048033, 34.8, 44.42 1491409753, 0, 0, 36.698160, -122.047218, 107.1, 4.76 1491410254, 0, 0, 36.697947, -122.046603, 106.7, 4.54 1491411896, 0, 0, 36.700287, -122.053102, 14.8, 35.27 rovNav(): 095: 3080 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017095vnta.txt 3080 in tList 3080 in {self->{rovT}} 3080 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491408150 & 1491409290 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ..............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 33.5972602639896 rovGDCListRefs(): dStats->sample_range = 97.8074404622072 rovGDCListRefs(): returning (1st elements): 1491407764 36.700152 -122.049495 87.2 2.68004426281881 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 095 (looping to 095) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt esecs, east, north, lat, lon, head, pres 1491408251, 0, 0, 36.699614, -122.049453, 137.7, 100.97 1491408752, 0, 0, 36.698772, -122.048653, 137.7, 100.50 1491409252, 0, 0, 36.698516, -122.048049, 34.8, 44.42 1491409753, 0, 0, 36.698182, -122.047196, 107.1, 4.76 1491410254, 0, 0, 36.697953, -122.046608, 106.7, 4.54 1491411896, 0, 0, 36.700330, -122.053113, 14.8, 35.27 rovNav(): 095: 3080 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017095vntaedited.txt 3080 in tList 3080 in {self->{rovT}} 3080 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1491408150 & 1491409290 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXXX...........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 33.5972602491385 rovGDCListRefs(): dStats->sample_range = 97.8074404713051 rovGDCListRefs(): returning (1st elements): 1491407764 36.699811 -122.049259 87.2 2.68004434361406 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.69849900 hash{lon} = -122.04820100 latMin = 30.698499 latMax = 42.698499 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.698499 AND 42.698499 AND Longitude BETWEEN -128.048201 AND -116.048201 Dive lat = 36.69849900, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.04820100 & lat = 36.69849900 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4023rov_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/vnta4023lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 554 shipNav(): $#{$self->{shipLat}}[last] = 36.699688 Position subsampling set to 3 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.5243 0.4395 0.0000 setROVcolorlines(): Assigning color line for O2... varColors = O2ColorframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/095/accesses.txt at bin\geopdvc.pl line 2382 s index0 = 0 0 1 setROVcolorlines(): Assigning color line for Sal... varColors = SalColors index0 = 1.0000 0.9240 0.0000 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.2846 0.7154 0.0000 makeTracks(): n = 3081 makeTracks(): Looping through 554 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4023tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4023mergedCtdNav.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/5/2017 3:56:00 PM' AND '4/5/2017 4:44:30 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '4/5/2017 3:56:00 PM' AND '4/5/2017 4:44:30 PM') _findIndex(): Looking for epoch secs 1491408068 ... Found time 1491408064 after 7 iterations. >>> p = 300: annotations(): 300, 1491408068, 04/05/17 16:01:08, V4023-01HD, 00:08:08:12, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4023/00_08_08_12.jpg, 99.8499984741211, descend, svonthun, u, 20066588 _findIndex(): Looking for epoch secs 1491409008 ... Found time 1491409005 after 7 iterations. >>> p = 1239: annotations(): 1239, 1491409008, 04/05/17 16:16:48, V4023-01HD, 00:23:47:27, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4023/00_23_47_27.jpg, 99.8499984741211, cruise, svonthun, u, 20066589 _findIndex(): Looking for epoch secs 1491409437 ... Found time 1491409439 after 7 iterations. >>> p = 1672: annotations(): 1672, 1491409437, 04/05/17 16:23:57, V4023-01HD, 00:30:55:29, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4023/00_30_55_29.jpg, 4.8899998664856, cruise, svonthun, u, 20066590 _findIndex(): Looking for epoch secs 1491409442 ... Found time 1491409439 after 7 iterations. >>> p = 1672: annotations(): 1672, 1491409442, 04/05/17 16:24:02, V4023-01HD, 00:31:00:29, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4023/00_31_00_29.jpg, 4.61999988555908, cruise, svonthun, u, 20066591 _findIndex(): Looking for epoch secs 1491410104 ... Found time 1491410102 after 6 iterations. >>> p = 2334: annotations(): 2334, 1491410104, 04/05/17 16:35:04, V4023-01HD, 00:42:02:24, equipment, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4023/00_42_02_24.jpg, 4.5, cruise, svonthun, u, 20066592 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4023anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 102.1 db", "T: 9.096 C", "S: 33.89", "O: 8.071 ml/l", "L: 81.41 %"] placeAnnotations(): Placing annotation 4: equipment Ancillary data = ["equipment", "P: 6.5 db", "T: 10.449 C", "S: 33.815", "O: 6.217 ml/l", "L: 72.05 %"] inline_name = vnta4023anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: equipment Ancillary data = ["equipment", "P: 101.9 db", "T: 9.684 C", "S: 33.554", "O: 13.984 ml/l", "L: 81.01 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 102.1 db", "T: 9.096 C", "S: 33.89", "O: 8.071 ml/l", "L: 81.41 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 6.5 db", "T: 10.449 C", "S: 33.815", "O: 6.217 ml/l", "L: 72.05 %"] placeAnnotations(): Placing annotation 4: equipment Ancillary data = ["equipmentframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/095/accesses.txt at bin\geopdvc.pl line 625 ", "P: 6.5 db", "T: 10.449 C", "S: 33.815", "O: 6.217 ml/l", "L: 72.05 %"] placeAnnotations(): Placing annotation 5: Ancillary data = ["", "P: 6.4 db", "T: 11.414 C", "S: 33.587", "O: 5.635 ml/l", "L: 72.32 %"] inline_name = vnta4023anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4023... frameGrabs(): matched 00_08_08_12 in [IMG]00_08_08_12.jpg 2017-04-14 00:05 227K  frameGrabs(): matched 00_23_47_27 in [IMG]00_23_47_27.jpg 2017-04-14 00:05 155K  frameGrabs(): matched 00_30_55_29 in [IMG]00_30_55_29.jpg 2017-04-14 00:06 224K  frameGrabs(): matched 00_31_00_29 in [IMG]00_31_00_29.jpg 2017-04-14 00:06 226K  frameGrabs(): matched 00_42_02_24 in [IMG]00_42_02_24.jpg 2017-04-14 00:06 225K  iList = 00_08_08_12.jpg 00_23_47_27.jpg 00_30_55_29.jpg 00_31_00_29.jpg 00_42_02_24.jpg _findIndex(): Looking for epoch secs 1491408068 ... Found time 1491408064 after 7 iterations. >>> p = 300: frameGrabs(): index = 300 EXPD::frameGrabs(): 300: Time=1491408060, Depth=101.12, Lat=36.699812, Lon=-122.049625, Pres=101.9, Temp=9.684, Sal=33.554, O2=13.984, Light=81.01 _findIndex(): Looking for epoch secs 1491409008 ... Found time 1491409005 after 7 iterations. >>> p = 1239: frameGrabs(): index = 1239 EXPD::frameGrabs(): 1239: Time=1491409005, Depth=101.32, Lat=36.698359, Lon=-122.048229, Pres=102.1, Temp=9.096, Sal=33.89, O2=8.071, Light=81.41 _findIndex(): Looking for epoch secs 1491409437 ... Found time 1491409439 after 7 iterations. >>> p = 1672: frameGrabs(): index = 1672 EXPD::frameGrabs(): 1672: Time=1491409440, Depth=6.45, Lat= 36.698510, Lon=-122.048015, Pres=6.5, Temp=10.449, Sal=33.815, O2=6.217, Light=72.05 _findIndex(): Looking for epoch secs 1491409442 ... Found time 1491409439 after 7 iterations. >>> p = 1672: frameGrabs(): index = 1672 EXPD::frameGrabs(): 1672: Time=1491409440, Depth=6.45, Lat= 36.698510, Lon=-122.048015, Pres=6.5, Temp=10.449, Sal=33.815, O2=6.217, Light=72.05 _findIndex(): Looking for epoch secs 1491410104 ... Found time 1491410102 after 6 iterations. >>> p = 2334: frameGrabs(): index = 2334 EXPD::frameGrabs(): 2334: Time=1491410100, Depth=6.35, Lat=36.697943, Lon=-122.046535, Pres=6.4, Temp=11.414, Sal=33.587, O2=5.635, Light=72.32 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 5 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_08_08_12.jpg 1491408068 fw= 0.0 cm XplaceImages(): indx = 300, geoPosition = 36.699812, -122.049625, -100.02112364809, rovDepth = 100.02112364809 inline_name = vnta4023fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_08_08_12.jpg 1491408068 fw= 0.0 cm XplaceImages(): indx = 300, geoPosition = 36.699812, -122.049625, -100.02112364809, rovDepth = 100.02112364809 placeImages(): Placing image 2 00_30_55_29.jpg 1491409437 fw= 0.0 cm XplaceImages(): indx = 1672, geoPosition = 36.698510, -122.048015, -4.57590674788708, rovDepth = 4.57590674788708 placeImages(): Placing image 4 00_42_02_24.jpg 1491410104 fw= 0.0 cm .placeImages(): indx = 2334, geoPosition = 36.697943, -122.046535, -4.63546257111409, rovDepth = 4.63546257111409 inline_name = vnta4023fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_08_08_12.jpg 1491408068 fw= 0.0 cm XplaceImages(): indx = 300, geoPosition = 36.699812, -122.049625, -100.02112364809, rovDepth = 100.02112364809 placeImages(): Placing image 1 00_23_47_27.jpg 1491409008 fw= 0.0 cm XplaceImages(): indx = 1239, geoPosition = 36.698359, -122.048229, -99.6044423785786, rovDepth = 99.6044423785786 placeImages(): Placing image 2 00_30_55_29.jpg 1491409437 fw= 0.0 cm XplaceImages(): indx = 1672, geoPosition = 36.698510, -122.048015, -4.57590674788708, rovDepth = 4.57590674788708 placeImages(): Placing image 3 00_31_00_29.jpg 1491409442 fw= 0.0 cm XplaceImages(): indx = 1672, geoPosition = 36.698510, -122.048015, -4.57590674788708, rovDepth = 4.57590674788708 placeImages(): Placing image 4 00_42_02_24.jpg 1491410104 fw= 0.0 cm XplaceImages(): indx = 2334, geoPosition = 36.697943, -122.046535, -4.63546257111409, rovDepth = 4.63546257111409 inline_name = vnta4023fg01.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/5/2017 3:56:00 PM' AND '4/5/2017 4:44:30 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4023.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5908,'/3Dreplay/3D.asp?dives=vnta4023','vnta4023','','','',4023 Executing sql: UPDATE Dive SET DiveLatMid = 36.6985, DiveLonMid = -122.0482, DiveDepthMid = -33.6 WHERE RovName = 'vnta' AND DiveNumber = 4023