Running bin\geopdvc.pl at Fri May 19 03:00:03 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4009 bin\geopdvc.pl: Getting Expedition object for diveno = 4009 and rname = vnta set_expd(): rov = vnta, diveno = 4009 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 = 4009 AND RovName = 'vnta' RS->Fields(0)->Value= 5839 RS->Fields(1)->Value= 1490109522 RS->Fields(2)->Value= 1490113920 RS->Fields(3)->Value= 3/21/2017 3:18:42 PM RS->Fields(4)->Value= 3/21/2017 4:32:00 PM set_expd(): $self->{EndDDD} = 080 set_expd(): $self->{StartEsecs} = 1490108322 set_expd(): $self->{EndEsecs} = 1490115120 set_expd(): $self->{EndDtg} = 3/21/2017 4:32:00 PM _readLogr(): Reading videologr data from 2017080datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1490108322 & 1490115120 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1490115128, self->{EndEsecs} = 1490115120 _readLogr(): #LOGHOST_SYSTEM_UTC = 679 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1490108327 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1490115118 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1490108327 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1490115118 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1490108202, Refined EndEsecs = 1490115240 _readLogr(): Reading videologr data from 2017080datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1490108202 & 1490115240 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1490115248, self->{EndEsecs} = 1490115240 _readLogr(): #LOGHOST_SYSTEM_UTC = 703 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1490108207 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1490115238 bin\geopdvc.pl: expdid = 5839 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 = 080 (looping to 080) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017080vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017080vnta.txt esecs, east, north, lat, lon, head, pres 1490110023, 0, 0, 36.795853, -121.849090, 230.3, 162.95 1490110524, 0, 0, 36.795867, -121.849895, 186.2, 298.10 1490111025, 0, 0, 36.795482, -121.849942, 112.9, 306.84 1490111526, 0, 0, 36.795680, -121.850300, 346.4, 292.25 1490112027, 0, 0, 36.796498, -121.850760, 350.0, 83.45 1490112527, 0, 0, 36.797412, -121.849775, 252.9, 37.58 1490113028, 0, 0, 36.798598, -121.849400, 244.7, 70.65 rovNav(): 080: 3898 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017080vnta.txt 3898 in tList 3898 in {self->{rovT}} 3898 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1490110200 & 1490112000 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 136.963446644954 rovGDCListRefs(): dStats->sample_range = 304.171768813224 rovGDCListRefs(): returning (1st elements): 1490109523 36.792850 -121.850732 270.9 2.77928169480394 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 080 (looping to 080) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017080vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017080vntaedited.txt esecs, east, north, lat, lon, head, pres 1490110023, 0, 0, 36.795848, -121.849064, 230.3, 162.95 1490110524, 0, 0, 36.795910, -121.849872, 186.2, 298.10 1490111025, 0, 0, 36.795478, -121.849940, 112.9, 306.84 1490111526, 0, 0, 36.795699, -121.850298, 346.4, 292.25 1490112027, 0, 0, 36.796531, -121.850749, 350.0, 83.45 1490112527, 0, 0, 36.797388, -121.849755, 252.9, 37.58 1490113028, 0, 0, 36.798197, -121.849065, 244.7, 70.65 rovNav(): 080: 3898 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017080vntaedited.txt 3898 in tList 3898 in {self->{rovT}} 3898 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1490110200 & 1490112000 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXXXXXXXXX..............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/080/accesses.txt at bin\geopdvc.pl line 2382 ..........................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 136.963446002567 rovGDCListRefs(): dStats->sample_range = 304.171769286222 rovGDCListRefs(): returning (1st elements): 1490109523 36.796088 -121.849752 270.9 2.77928089841408 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79601700 hash{lon} = -121.84980300 latMin = 30.796017 latMax = 42.796017 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.796017 AND 42.796017 AND Longitude BETWEEN -127.849803 AND -115.849803 Dive lat = 36.79601700, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.84980300 & lat = 36.79601700 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4009rov_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/vnta4009lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 703 shipNav(): $#{$self->{shipLat}}[last] = 36.803011 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 = 3899 makeTracks(): Looping through 703 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4009tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4009mergedCtdNav.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 '3/21/2017 3:18:42 PM' AND '3/21/2017 4:32:00 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '3/21/2017 3:18:42 PM' AND '3/21/2017 4:32:00 PM') _findIndex(): Looking for epoch secs 1490111282 ... Found time 1490111284 after 8 iterations. >>> p = 1758: annotations(): 1758, 1490111282, 03/21/17 15:48:02, V4009-01HD, 00:31:36:14, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4009/00_31_36_14.jpg, 303.559997558594, descend, ventana, u, 20066369 _findIndex(): Looking for epoch secs 1490111313 ... Found time 1490111314 after 8 iterations. >>> p = 1788: annotations(): 1788, 1490111313, 03/21/17 15:48:33, V4009-01HD, 00:32:07:13, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4009/00_32_07_13.jpg, 303.910003662109, descend, ventana, u, 20066370 _findIndex(): Looking for epoch secs 1490111351 ... Found tframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/080/accesses.txt at bin\geopdvc.pl line 625 ime 1490111353 after 4 iterations. >>> p = 1827: annotations(): 1827, 1490111351, 03/21/17 15:49:11, V4009-01HD, 00:32:45:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4009/00_32_45_12.jpg, 303.980010986328, descend, ventana, u, 20066371 _findIndex(): Looking for epoch secs 1490111367 ... Found time 1490111368 after 7 iterations. >>> p = 1842: annotations(): 1842, 1490111367, 03/21/17 15:49:27, V4009-01HD, 00:33:01:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4009/00_33_01_12.jpg, 304.779998779297, descend, ventana, u, 20066372 _findIndex(): Looking for epoch secs 1490111385 ... Found time 1490111383 after 6 iterations. >>> p = 1857: annotations(): 1857, 1490111385, 03/21/17 15:49:45, V4009-01HD, 00:33:19:11, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4009/00_33_19_11.jpg, 305.459991455078, descend, ventana, u, 20066373 _findIndex(): Looking for epoch secs 1490113219 ... Found time 1490113214 after 6 iterations. >>> p = 3684: annotations(): 3684, 1490113219, 03/21/17 16:20:19, V4009-02HD, 01:03:14:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4009/01_03_14_10.jpg, 51.2000007629395, descend, ventana, u, 20066374 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4009anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 307.6 db", "T: 8.149 C", "S: 34.037", "O: 1.621 ml/l", "L: 63.78 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 308.8 db", "T: 8.146 C", "S: 34.037", "O: 1.62 ml/l", "L: 64.98 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 56 db", "T: 11.206 C", "S: 33.398", "O: 4.546 ml/l", "L: 75.71 %"] inline_name = vnta4009anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 308 db", "T: 8.133 C", "S: 34.04", "O: 1.602 ml/l", "L: 63.79 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 307.6 db", "T: 8.149 C", "S: 34.037", "O: 1.621 ml/l", "L: 63.78 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 308.1 db", "T: 8.151 C", "S: 34.037", "O: 1.622 ml/l", "L: 64.21 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 308.8 db", "T: 8.146 C", "S: 34.037", "O: 1.62 ml/l", "L: 64.98 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 309.4 db", "T: 8.125 C", "S: 34.04", "O: 1.609 ml/l", "L: 64.35 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 56 db", "T: 11.206 C", "S: 33.398", "O: 4.546 ml/l", "L: 75.71 %"] inline_name = vnta4009anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4009... frameGrabs(): matched 00_31_36_14 in [IMG]00_31_36_14.jpg 2017-03-28 00:33 274K  frameGrabs(): matched 00_32_07_13 in [IMG]00_32_07_13.jpg 2017-03-28 00:34 284K  frameGrabs(): matched 00_32_45_12 in [IMG]00_32_45_12.jpg 2017-03-28 00:34 319K  frameGrabs(): matched 00_33_01_12 in [IMG]00_33_01_12.jpg 2017-03-28 00:34 303K  frameGrabs(): matched 00_33_19_11 in [IMG]00_33_19_11.jpg 2017-03-28 00:34 268K  frameGrabs(): matched 01_03_14_10 in [IMG]01_03_14_10.jpg 2017-03-28 00:34 231K  iList = 00_31_36_14.jpg 00_32_07_13.jpg 00_32_45_12.jpg 00_33_01_12.jpg 00_33_19_11.jpg 01_03_14_10.jpg _findIndex(): Looking for epoch secs 1490111282 ... Found time 1490111284 after 8 iterations. >>> p = 1758: frameGrabs(): index = 1758 EXPD::frameGrabs(): 1758: Time=1490111280, Depth=305.5, Lat=36.795481, Lon=-121.849945, Pres=308, Temp=8.133, Sal=34.04, O2=1.602, Light=63.79 _findIndex(): Looking for epoch secs 1490111313 ... Found time 1490111314 after 8 iterations. >>> p = 1788: frameGrabs(): index = 1788 EXPD::frameGrabs(): 1788: Time=1490111310, Depth=305.1, Lat=36.795486, Lon=-121.849949, Pres=307.6, Temp=8.149, Sal=34.037, O2=1.621, Light=63.78 _findIndex(): Looking for epoch secs 1490111351 ... Found time 1490111353 after 4 iterations. >>> p = 1827: frameGrabs(): index = 1827 EXPD::frameGrabs(): 1827: Time=1490111355, Depth=305.6, Lat=36.795491, Lon=-121.849924, Pres=308.1, Temp=8.151, Sal=34.037, O2=1.622, Light=64.21 _findIndex(): Looking for epoch secs 1490111367 ... Found time 1490111368 after 7 iterations. >>> p = 1842: frameGrabs(): index = 1842 EXPD::frameGrabs(): 1842: Time=1490111370, Depth=306.29, Lat=36.795481, Lon=-121.849921, Pres=308.8, Temp=8.146, Sal=34.037, O2=1.62, Light=64.98 _findIndex(): Looking for epoch secs 1490111385 ... Found time 1490111383 after 6 iterations. >>> p = 1857: frameGrabs(): index = 1857 EXPD::frameGrabs(): 1857: Time=1490111385, Depth=306.89, Lat=36.795475, Lon=-121.849922, Pres=309.4, Temp=8.125, Sal=34.04, O2=1.609, Light=64.35 _findIndex(): Looking for epoch secs 1490113219 ... Found time 1490113214 after 6 iterations. >>> p = 3684: frameGrabs(): index = 3684 EXPD::frameGrabs(): 3684: Time=1490113215, Depth=55.58, Lat=36.798322, Lon=-121.849315, Pres=56, Temp=11.206, Sal=33.398, O2=4.546, Light=75.71 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_31_36_14.jpg 1490111282 fw= 0.0 cm XplaceImages(): indx = 1758, geoPosition = 36.795481, -121.849945, -303.471915182451, rovDepth = 303.471915182451 inline_name = vnta4009fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_31_36_14.jpg 1490111282 fw= 0.0 cm XplaceImages(): indx = 1758, geoPosition = 36.795481, -121.849945, -303.471915182451, rovDepth = 303.471915182451 placeImages(): Placing image 2 00_32_45_12.jpg 1490111351 fw= 0.0 cm XplaceImages(): indx = 1827, geoPosition = 36.795491, -121.849924, -303.997213756228, rovDepth = 303.997213756228 placeImages(): Placing image 4 00_33_19_11.jpg 1490111385 fw= 0.0 cm XplaceImages(): indx = 1857, geoPosition = 36.795475, -121.849922, -305.345144671428, rovDepth = 305.345144671428 inline_name = vnta4009fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_31_36_14.jpg 1490111282 fw= 0.0 cm XplaceImages(): indx = 1758, geoPosition = 36.795481, -121.849945, -303.471915182451, rovDepth = 303.471915182451 placeImages(): Placing image 1 00_32_07_13.jpg 1490111313 fw= 0.0 cm XplaceImages(): indx = 1788, geoPosition = 36.795486, -121.849949, -303.908012295535, rovDepth = 303.908012295535 placeImages(): Placing image 2 00_32_45_12.jpg 1490111351 fw= 0.0 cm XplaceImages(): indx = 1827, geoPosition = 36.795491, -121.849924, -303.997213756228, rovDepth = 303.997213756228 placeImages(): Placing image 3 00_33_01_12.jpg 1490111367 fw= 0.0 cm XplaceImages(): indx = 1842, geoPosition = 36.795481, -121.849921, -304.770292888642, rovDepth = 304.770292888642 placeImages(): Placing image 4 00_33_19_11.jpg 1490111385 fw= 0.0 cm XplaceImages(): indx = 1857, geoPosition = 36.795475, -121.849922, -305.345144671428, rovDepth = 305.345144671428 placeImages(): Placing image 5 01_03_14_10.jpg 1490113219 fw= 0.0 cm XplaceImages(): indx = 3684, geoPosition = 36.798322, -121.849315, -54.3975068670409, rovDepth = 54.3975068670409 inline_name = vnta4009fg01.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 '3/21/2017 3:18:42 PM' AND '3/21/2017 4:32:00 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4009.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5839,'/3Dreplay/3D.asp?dives=vnta4009','vnta4009','','','',4009 Executing sql: UPDATE Dive SET DiveLatMid = 36.7960, DiveLonMid = -121.8498, DiveDepthMid = -137.0 WHERE RovName = 'vnta' AND DiveNumber = 4009