Running bin\geopdvc.pl at Mon Oct 2 03:00:03 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4057 bin\geopdvc.pl: Getting Expedition object for diveno = 4057 and rname = vnta set_expd(): rov = vnta, diveno = 4057 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 = 4057 AND RovName = 'vnta' RS->Fields(0)->Value= 6053 RS->Fields(1)->Value= 1501874139 RS->Fields(2)->Value= 1501878226 RS->Fields(3)->Value= 8/4/2017 7:15:39 PM RS->Fields(4)->Value= 8/4/2017 8:23:46 PM set_expd(): $self->{EndDDD} = 216 set_expd(): $self->{StartEsecs} = 1501872939 set_expd(): $self->{EndEsecs} = 1501879426 set_expd(): $self->{EndDtg} = 8/4/2017 8:23:46 PM _readLogr(): Reading videologr data from 2017216datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1501872939 & 1501879426 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1501879434, self->{EndEsecs} = 1501879426 _readLogr(): #LOGHOST_SYSTEM_UTC = 648 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1501872943 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1501879424 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1501872943 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1501879424 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1501872819, Refined EndEsecs = 1501879546 _readLogr(): Reading videologr data from 2017216datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1501872819 & 1501879546 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1501879554, self->{EndEsecs} = 1501879546 _readLogr(): #LOGHOST_SYSTEM_UTC = 672 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1501872823 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1501879544 bin\geopdvc.pl: expdid = 6053 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 = 216 (looping to 216) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017216vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017216vnta.txt esecs, east, north, lat, lon, head, pres 1501874640, 0, 0, 36.797442, -121.887882, 58.8, 155.59 1501875141, 0, 0, 36.797825, -121.887532, 79.8, 318.92 1501875642, 0, 0, 36.797870, -121.887330, 8.2, 433.55 1501876143, 0, 0, 36.798012, -121.887122, -1.2, 427.61 1501876644, 0, 0, 36.797873, -121.887373, -134.8, 403.90 1501877145, 0, 0, 36.797717, -121.888603, -136.8, 178.10 1501877645, 0, 0, 36.794100, -121.887910, -141.9, 8.99 1501878146, 0, 0, 36.799465, -121.886233, -144.7, 9.14 rovNav(): 216: 4078 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017216vnta.txt 4078 in tList 4078 in {self->{rovT}} 4078 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1501877475 & 1501875885 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ..................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 259.907019233997 rovGDCListRefs(): dStats->sample_range = 428.043580203907 rovGDCListRefs(): returning (1st elements): 1501874140 36.799412 -121.879867 -32.7 2.58076133552562 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 216 (looping to 216) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017216vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017216vntaedited.txt esecs, east, north, lat, lon, head, pres 1501874640, 0, 0, 36.797466, -121.887864, 58.8, 155.59 1501875141, 0, 0, 36.797824, -121.887535, 79.8, 318.92 1501875642, 0, 0, 36.797873, -121.887326, 8.2, 433.55 1501876143, 0, 0, 36.798020, -121.887231, 358.8, 427.61 1501876644, 0, 0, 36.797873, -121.887392, 225.2, 403.90 1501877145, 0, 0, 36.797734, -121.888562, 223.2, 178.10 1501877645, 0, 0, 36.794261, -121.887633, 218.1, 8.99 1501878146, 0, 0, 36.798776, -121.887187, 215.3, 9.14 rovNav(): 216: 4078 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017216vntaedited.txt 4078 in tList 4078 in {self->{rovT}} 4078 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1501877475 & 1501875885 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXXXXXXXXXX.............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/216/accesses.txt at bin\geopdvc.pl line 2382 ...........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 259.907019141995 rovGDCListRefs(): dStats->sample_range = 428.043578594831 rovGDCListRefs(): returning (1st elements): 1501874140 36.796128 -121.888667 327.3 2.58076208556188 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79781300 hash{lon} = -121.88764900 latMin = 30.797813 latMax = 42.797813 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.797813 AND 42.797813 AND Longitude BETWEEN -127.887649 AND -115.887649 Dive lat = 36.79781300, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.88764900 & lat = 36.79781300 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4057rov_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/vnta4057lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 672 shipNav(): $#{$self->{shipLat}}[last] = 36.801024 Position subsampling set to 4 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 = 4079 makeTracks(): Looping through 672 point of ship position data... makeTracks(): posSubsample = 4 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4057tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4057mergedCtdNav.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 '8/4/2017 7:15:39 PM' AND '8/4/2017 8:23:46 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '8/4/2017 7:15:39 PM' AND '8/4/2017 8:23:46 PM') _findIndex(): Looking for epoch secs 1501874697 ... Found time 1501874697 after 7 iterations. >>> p = 556: annotations(): 556, 1501874697, 08/04/17 19:24:57, V4057-01HD, 00:07:42:21, physical object, http://search.mbari.org/ARCHIVE/frameGraframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/216/accesses.txt at bin\geopdvc.pl line 625 bs/Ventana/images/4057/00_07_42_21.jpg, 170.940002441406, descend, mschwehr, u, 20068688 _findIndex(): Looking for epoch secs 1501874800 ... Found time 1501874801 after 8 iterations. >>> p = 660: annotations(): 660, 1501874800, 08/04/17 19:26:40, V4057-01HD, 00:09:25:18, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4057/00_09_25_18.jpg, 214.389999389648, descend, mschwehr, u, 20068689 _findIndex(): Looking for epoch secs 1501874812 ... Found time 1501874809 after 6 iterations. >>> p = 668: annotations(): 668, 1501874812, 08/04/17 19:26:52, V4057-01HD, 00:09:37:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4057/00_09_37_17.jpg, 219.979995727539, descend, mschwehr, u, 20068690 _findIndex(): Looking for epoch secs 1501877910 ... Found time 1501877906 after 5 iterations. >>> p = 3759: annotations(): 3759, 1501877910, 08/04/17 20:18:30, V4057-01HD, 01:01:13:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4057/01_01_13_00.jpg, 8.84000015258789, descend, mschwehr, u, 20068691 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4057anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 219.6 db", "T: 8.745 C", "S: 34.032", "O: 1.599 ml/l", "L: 80.27 %"] placeAnnotations(): Placing annotation 4: Ancillary data = ["", "P: 11.4 db", "T: 14.093 C", "S: 33.679", "O: 7.435 ml/l", "L: 69.46 %"] inline_name = vnta4057anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 175.2 db", "T: 9.149 C", "S: 33.972", "O: 1.875 ml/l", "L: 80.46 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 219.6 db", "T: 8.745 C", "S: 34.032", "O: 1.599 ml/l", "L: 80.27 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 219.6 db", "T: 8.745 C", "S: 34.032", "O: 1.599 ml/l", "L: 80.27 %"] placeAnnotations(): Placing annotation 4: Ancillary data = ["", "P: 11.4 db", "T: 14.093 C", "S: 33.679", "O: 7.435 ml/l", "L: 69.46 %"] inline_name = vnta4057anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4057... frameGrabs(): matched 00_07_42_21 in [IMG]00_07_42_21.jpg 2017-08-08 00:05 57K  frameGrabs(): matched 00_09_25_18 in [IMG]00_09_25_18.jpg 2017-08-08 00:05 337K  frameGrabs(): matched 00_09_37_17 in [IMG]00_09_37_17.jpg 2017-08-08 00:05 336K  frameGrabs(): matched 01_01_13_00 in [IMG]01_01_13_00.jpg 2017-08-08 00:05 238K  iList = 00_07_42_21.jpg 00_09_25_18.jpg 00_09_37_17.jpg 01_01_13_00.jpg _findIndex(): Looking for epoch secs 1501874697 ... Found time 1501874697 after 7 iterations. >>> p = 556: frameGrabs(): index = 556 EXPD::frameGrabs(): 556: Time=1501874700, Depth=173.83, Lat=36.797624, Lon=-121.887658, Pres=175.2, Temp=9.149, Sal=33.972, O2=1.875, Light=80.46 _findIndex(): Looking for epoch secs 1501874800 ... Found time 1501874801 after 8 iterations. >>> p = 660: frameGrabs(): index = 660 EXPD::frameGrabs(): 660: Time=1501874805, Depth=217.86, Lat=36.797765, Lon=-121.887680, Pres=219.6, Temp=8.745, Sal=34.032, O2=1.599, Light=80.27 _findIndex(): Looking for epoch secs 1501874812 ... Found time 1501874809 after 6 iterations. >>> p = 668: frameGrabs(): index = 668 EXPD::frameGrabs(): 668: Time=1501874805, Depth=217.86, Lat=36.797773, Lon=-121.887684, Pres=219.6, Temp=8.745, Sal=34.032, O2=1.599, Light=80.27 _findIndex(): Looking for epoch secs 1501877910 ... Found time 1501877906 after 5 iterations. >>> p = 3759: frameGrabs(): index = 3759 EXPD::frameGrabs(): 3759: Time=1501877910, Depth=11.32, Lat= 36.798245, Lon=-121.887255, Pres=11.4, Temp=14.093, Sal=33.679, O2=7.435, Light=69.46 bin\geopdvc.pl: Number of images returned from expd->frameGrabs(every=>1)} = 4 bin\geopdvc.pl: Calling placeImages with every 10 image... placeImages(): Placing image 0 00_07_42_21.jpg 1501874697 fw= 0.0 cm XplaceImages(): indx = 556, geoPosition = 36.797624, -121.887658, -170.934515657893, rovDepth = 170.934515657893 inline_name = vnta4057fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_07_42_21.jpg 1501874697 fw= 0.0 cm XplaceImages(): indx = 556, geoPosition = 36.797624, -121.887658, -170.934515657893, rovDepth = 170.934515657893 placeImages(): Placing image 2 00_09_37_17.jpg 1501874812 fw= 0.0 cm XplaceImages(): indx = 668, geoPosition = 36.797773, -121.887684, -217.721050177807, rovDepth = 217.721050177807 inline_name = vnta4057fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_07_42_21.jpg 1501874697 fw= 0.0 cm XplaceImages(): indx = 556, geoPosition = 36.797624, -121.887658, -170.934515657893, rovDepth = 170.934515657893 placeImages(): Placing image 1 00_09_25_18.jpg 1501874800 fw= 0.0 cm XplaceImages(): indx = 660, geoPosition = 36.797765, -121.887680, -214.389426017712, rovDepth = 214.389426017712 placeImages(): Placing image 2 00_09_37_17.jpg 1501874812 fw= 0.0 cm XplaceImages(): indx = 668, geoPosition = 36.797773, -121.887684, -217.721050177807, rovDepth = 217.721050177807 placeImages(): Placing image 3 01_01_13_00.jpg 1501877910 fw= 0.0 cm XplaceImages(): indx = 3759, geoPosition = 36.798245, -121.887255, -8.88363776475125, rovDepth = 8.88363776475125 inline_name = vnta4057fg01.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 '8/4/2017 7:15:39 PM' AND '8/4/2017 8:23:46 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4057.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',6053,'/3Dreplay/3D.asp?dives=vnta4057','vnta4057','','','',4057 Executing sql: UPDATE Dive SET DiveLatMid = 36.7978, DiveLonMid = -121.8876, DiveDepthMid = -259.9 WHERE RovName = 'vnta' AND DiveNumber = 4057