Running bin\geopdvc.pl at Mon Oct 30 03:00:22 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4073 bin\geopdvc.pl: Getting Expedition object for diveno = 4073 and rname = vnta set_expd(): rov = vnta, diveno = 4073 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 = 4073 AND RovName = 'vnta' RS->Fields(0)->Value= 6083 RS->Fields(1)->Value= 1504287232 RS->Fields(2)->Value= 1504289651 RS->Fields(3)->Value= 9/1/2017 5:33:52 PM RS->Fields(4)->Value= 9/1/2017 6:14:11 PM set_expd(): $self->{EndDDD} = 244 set_expd(): $self->{StartEsecs} = 1504286032 set_expd(): $self->{EndEsecs} = 1504290851 set_expd(): $self->{EndDtg} = 9/1/2017 6:14:11 PM _readLogr(): Reading videologr data from 2017244datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1504286032 & 1504290851 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1504290858, self->{EndEsecs} = 1504290851 _readLogr(): #LOGHOST_SYSTEM_UTC = 481 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1504286037 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1504290848 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1504286037 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1504290848 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1504285912, Refined EndEsecs = 1504290971 _readLogr(): Reading videologr data from 2017244datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1504285912 & 1504290971 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1504290978, self->{EndEsecs} = 1504290971 _readLogr(): #LOGHOST_SYSTEM_UTC = 505 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1504285917 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1504290968 bin\geopdvc.pl: expdid = 6083 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 = 244 (looping to 244) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017244vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017244vnta.txt esecs, east, north, lat, lon, head, pres 1504287310, 0, 0, 36.798765, -121.855022, -26.4, 34.24 1504287811, 0, 0, 36.799262, -121.854947, -53.8, 239.96 1504288312, 0, 0, 36.800023, -121.856062, -74.5, 328.73 1504288813, 0, 0, 36.799493, -121.856103, -15.5, 318.61 1504289314, 0, 0, 36.799160, -121.855890, -13.0, 87.36 rovNav(): 244: 2412 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017244vnta.txt 2412 in tList 2412 in {self->{rovT}} 2412 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1504288815 & 1504288185 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 232.702741723341 rovGDCListRefs(): dStats->sample_range = 325.340909116661 rovGDCListRefs(): returning (1st elements): 1504287235 36.797813 -121.856355 -9.0 2.68002111167062 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 244 (looping to 244) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017244vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017244vntaedited.txt esecs, east, north, lat, lon, head, pres 1504287310, 0, 0, 36.798744, -121.855039, 333.6, 34.24 1504287811, 0, 0, 36.799262, -121.854954, 306.2, 239.96 1504288312, 0, 0, 36.800025, -121.856050, 285.5, 328.73 1504288813, 0, 0, 36.799492, -121.856102, 344.5, 318.61 1504289314, 0, 0, 36.799144, -121.855901, 347.0, 87.36 rovNav(): 244: 2412 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017244vntaedited.txt 2412 in tList 2412 in {self->{rovT}} 2412 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1504288815 & 1504288185 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXX...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/244/accesses.txt at bin\geopdvc.pl line 2382 ...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 232.702741640968 rovGDCListRefs(): dStats->sample_range = 325.340909212295 rovGDCListRefs(): returning (1st elements): 1504287235 36.798308 -121.855505 351.0 2.68002099426851 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79933800 hash{lon} = -121.85582000 latMin = 30.799338 latMax = 42.799338 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.799338 AND 42.799338 AND Longitude BETWEEN -127.85582 AND -115.85582 Dive lat = 36.79933800, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.85582000 & lat = 36.79933800 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4073rov_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/vnta4073lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 505 shipNav(): $#{$self->{shipLat}}[last] = 36.804115 Position subsampling set to 2 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 = 2413 makeTracks(): Looping through 505 point of ship position data... makeTracks(): posSubsample = 2 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4073tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4073mergedCtdNav.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 '9/1/2017 5:33:52 PM' AND '9/1/2017 6:14:11 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '9/1/2017 5:33:52 PM' AND '9/1/2017 6:14:11 PM') _findIndex(): Looking for epoch secs 1504288569 ... Found time 1504288574 after 6 iterations. >>> p = 1337: annotations(): 1337, 1504288569, 09/01/17 17:56:09, V4073-01HD, 00:04:26:26, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4073/00_04_26_26.jpg, 327.769989013672, descend, ventana, u, 20069504 _findIndex(): Looking for epoch secs 1504288586 ... Found time 1504288583 after 7 iterations. >>> p = 1346: annotations(): 1346, 1504288586, 09/01/17 17:56:26, V4073-01HD, 00:04:43:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4073/00_04_43_10.jpg, 327.760009765625, descend, ventana, u, 20069505 _findIndex(): Looking for epoch secs 1504288596 ... Found time 1504288593 after 3 iterations. >>> p = 1356: annotations(): 1356, 1504288596, 09/01/17 17:56:36, V4073-01HD, 00:04:54:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4073/00_04_54_10.jpg, 327.660003662109, descend, ventana, u, 20069506 _findIndex(): Looking for epoch secs 1504288603 ... Found time 1504288602 after 7 iterations. >>> p = 1365: annotations(): 1365, 1504288603, 09/01/17 17:56:43, V4073-01HD, 00:05:01:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4073/00_05_01_10.jpg, 327.700012207031, descend, ventana, u, 20069507 _findIndex(): Looking for epoch secs 1504288611 ... Found time 1504288611 after 6 iterations. >>> p = 1374: annotations(): 1374, 1504288611, 09/01/17 17:56:51, V4073-01HD, 00:05:08:24, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4073/00_05_08_24.jpg, 327.489990234375, descend, ventana, u, 20069508 _findIndex(): Looking for epoch secs 1504288618 ... Found time 1504288620 after 7 iterations. >>> p = 1383: annotations(): 1383, 1504288618, 09/01/17 17:56:58, V4073-01HD, 00:05:15:24, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4073/00_05_15_24.jpg, 327.429992675781, descend, ventana, u, 20069509 _findIndex(): Looking for epoch secs 1504288628 ... Found time 1504288630 after 5 iterations. >>> p = 1393: annotations(): 1393, 1504288628, 09/01/17 17:57:08, V4073-01HD, 00:05:26:09, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4073/00_05_26_09.jpg, 327.209991455078, descend, ventana, u, 20069510 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4073anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 332.4 db", "T: 7.554 C", "S: 34.166", "O: 0.887 ml/l", "L: 76.75 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 332.2 db", "T: 7.559 C", "S: 34.166", "O: 0.887 ml/l", "L: 76.81 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 332 db", "T: 7.537 C", "S: 34.165", "O: 0.886 ml/l", "L: 76.6 %"] inline_name = vnta4073anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 332.4 db", "T: 7.566 C", "S: 34.168", "O: 0.886 ml/l", "L: 76.69 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 332.4 db", "T: 7.554 C", "S: 34.166", "O: 0.887 ml/l", "L: 76.75 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 332.4 db", "T: 7.554 C", "S: 34.166", "O: 0.887 ml/l", "L: 76.75 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 332.2 db", "T: 7.559 C", "S: 34.166", "O: 0.887 ml/l", "L: 76.81 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 332.2 db", "T: 7.559 C", "S: 34.166", "O: 0.887 ml/l", "L: 76.81 %"] placeAnnotations(): Placing annotaframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/244/accesses.txt at bin\geopdvc.pl line 625 tion 6: physical object Ancillary data = ["physical object", "P: 332 db", "T: 7.537 C", "S: 34.165", "O: 0.886 ml/l", "L: 76.6 %"] placeAnnotations(): Placing annotation 7: Ancillary data = ["", "P: 331.3 db", "T: 7.54 C", "S: 34.166", "O: 0.886 ml/l", "L: 76.44 %"] inline_name = vnta4073anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4073... frameGrabs(): matched 00_04_26_26 in [IMG]00_04_26_26.jpg 2017-09-08 00:06 334K  frameGrabs(): matched 00_04_43_10 in [IMG]00_04_43_10.jpg 2017-09-08 00:06 339K  frameGrabs(): matched 00_04_54_10 in [IMG]00_04_54_10.jpg 2017-09-08 00:06 344K  frameGrabs(): matched 00_05_01_10 in [IMG]00_05_01_10.jpg 2017-09-08 00:06 330K  frameGrabs(): matched 00_05_08_24 in [IMG]00_05_08_24.jpg 2017-09-08 00:06 339K  frameGrabs(): matched 00_05_15_24 in [IMG]00_05_15_24.jpg 2017-09-08 00:06 334K  frameGrabs(): matched 00_05_26_09 in [IMG]00_05_26_09.jpg 2017-09-08 00:06 316K  iList = 00_04_26_26.jpg 00_04_43_10.jpg 00_04_54_10.jpg 00_05_01_10.jpg 00_05_08_24.jpg 00_05_15_24.jpg 00_05_26_09.jpg _findIndex(): Looking for epoch secs 1504288569 ... Found time 1504288574 after 6 iterations. >>> p = 1337: frameGrabs(): index = 1337 EXPD::frameGrabs(): 1337: Time=1504288575, Depth=329.68, Lat= 36.800039, Lon=-121.856180, Pres=332.4, Temp=7.566, Sal=34.168, O2=0.886, Light=76.69 _findIndex(): Looking for epoch secs 1504288586 ... Found time 1504288583 after 7 iterations. >>> p = 1346: frameGrabs(): index = 1346 EXPD::frameGrabs(): 1346: Time=1504288590, Depth=329.68, Lat=36.800039, Lon=-121.856179, Pres=332.4, Temp=7.554, Sal=34.166, O2=0.887, Light=76.75 _findIndex(): Looking for epoch secs 1504288596 ... Found time 1504288593 after 3 iterations. >>> p = 1356: frameGrabs(): index = 1356 EXPD::frameGrabs(): 1356: Time=1504288590, Depth=329.68, Lat=36.800040, Lon=-121.856182, Pres=332.4, Temp=7.554, Sal=34.166, O2=0.887, Light=76.75 _findIndex(): Looking for epoch secs 1504288603 ... Found time 1504288602 after 7 iterations. >>> p = 1365: frameGrabs(): index = 1365 EXPD::frameGrabs(): 1365: Time=1504288605, Depth=329.48, Lat= 36.800040, Lon=-121.856185, Pres=332.2, Temp=7.559, Sal=34.166, O2=0.887, Light=76.81 _findIndex(): Looking for epoch secs 1504288611 ... Found time 1504288611 after 6 iterations. >>> p = 1374: frameGrabs(): index = 1374 EXPD::frameGrabs(): 1374: Time=1504288605, Depth=329.48, Lat=36.800040, Lon=-121.856186, Pres=332.2, Temp=7.559, Sal=34.166, O2=0.887, Light=76.81 _findIndex(): Looking for epoch secs 1504288618 ... Found time 1504288620 after 7 iterations. >>> p = 1383: frameGrabs(): index = 1383 EXPD::frameGrabs(): 1383: Time=1504288620, Depth=329.29, Lat= 36.800038, Lon=-121.856180, Pres=332, Temp=7.537, Sal=34.165, O2=0.886, Light=76.6 _findIndex(): Looking for epoch secs 1504288628 ... Found time 1504288630 after 5 iterations. >>> p = 1393: frameGrabs(): index = 1393 EXPD::frameGrabs(): 1393: Time=1504288635, Depth=328.59, Lat= 36.800034, Lon=-121.856172, Pres=331.3, Temp=7.54, Sal=34.166, O2=0.886, Light=76.44 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 7 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_04_26_26.jpg 1504288569 fw= 0.0 cm XplaceImages(): indx = 1337, geoPosition = 36.800039, -121.856180, -327.782786596822, rovDepth = 327.782786596822 inline_name = vnta4073fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_04_26_26.jpg 1504288569 fw= 0.0 cm XplaceImages(): indx = 1337, geoPosition = 36.800039, -121.856180, -327.782786596822, rovDepth = 327.782786596822 placeImages(): Placing image 2 00_04_54_10.jpg 1504288596 fw= 0.0 cm XplaceImages(): indx = 1356, geoPosition = 36.800040, -121.856182, -327.79269669818, rovDepth = 327.79269669818 placeImages(): Placing image 4 00_05_08_24.jpg 1504288611 fw= 0.0 cm XplaceImages(): indx = 1374, geoPosition = 36.800040, -121.856186, -327.485482433826, rovDepth = 327.485482433826 placeImages(): Placing image 6 00_05_26_09.jpg 1504288628 fw= 0.0 cm XplaceImages(): indx = 1393, geoPosition = 36.800034, -121.856172, -327.098986587951, rovDepth = 327.098986587951 inline_name = vnta4073fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_04_26_26.jpg 1504288569 fw= 0.0 cm XplaceImages(): indx = 1337, geoPosition = 36.800039, -121.856180, -327.782786596822, rovDepth = 327.782786596822 placeImages(): Placing image 1 00_04_43_10.jpg 1504288586 fw= 0.0 cm .placeImages(): indx = 1346, geoPosition = 36.800039, -121.856179, -327.802606857076, rovDepth = 327.802606857076 placeImages(): Placing image 2 00_04_54_10.jpg 1504288596 fw= 0.0 cm .placeImages(): indx = 1356, geoPosition = 36.800040, -121.856182, -327.79269669818, rovDepth = 327.79269669818 placeImages(): Placing image 3 00_05_01_10.jpg 1504288603 fw= 0.0 cm .placeImages(): indx = 1365, geoPosition = 36.800040, -121.856185, -327.653954829422, rovDepth = 327.653954829422 placeImages(): Placing image 4 00_05_08_24.jpg 1504288611 fw= 0.0 cm .placeImages(): indx = 1374, geoPosition = 36.800040, -121.856186, -327.485482433826, rovDepth = 327.485482433826 placeImages(): Placing image 5 00_05_15_24.jpg 1504288618 fw= 0.0 cm .placeImages(): indx = 1383, geoPosition = 36.800038, -121.856180, -327.406201316469, rovDepth = 327.406201316469 placeImages(): Placing image 6 00_05_26_09.jpg 1504288628 fw= 0.0 cm .placeImages(): indx = 1393, geoPosition = 36.800034, -121.856172, -327.098986587951, rovDepth = 327.098986587951 inline_name = vnta4073fg01.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 '9/1/2017 5:33:52 PM' AND '9/1/2017 6:14:11 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4073.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',6083,'/3Dreplay/3D.asp?dives=vnta4073','vnta4073','','','',4073 Executing sql: UPDATE Dive SET DiveLatMid = 36.7993, DiveLonMid = -121.8558, DiveDepthMid = -232.7 WHERE RovName = 'vnta' AND DiveNumber = 4073