Running bin\geopdvc.pl at Thu Apr 6 03:00:02 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4000 bin\geopdvc.pl: Getting Expedition object for diveno = 4000 and rname = vnta set_expd(): rov = vnta, diveno = 4000 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 = 4000 AND RovName = 'vnta' RS->Fields(0)->Value= 5811 RS->Fields(1)->Value= 1486407924 RS->Fields(2)->Value= 1486410374 RS->Fields(3)->Value= 2/6/2017 7:05:24 PM RS->Fields(4)->Value= 2/6/2017 7:46:14 PM set_expd(): $self->{EndDDD} = 037 set_expd(): $self->{StartEsecs} = 1486406724 set_expd(): $self->{EndEsecs} = 1486411574 set_expd(): $self->{EndDtg} = 2/6/2017 7:46:14 PM _readLogr(): Reading videologr data from 2017037datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1486406724 & 1486411574 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1486411580, self->{EndEsecs} = 1486411574 _readLogr(): #LOGHOST_SYSTEM_UTC = 484 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1486406730 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1486411570 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1486406730 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1486411570 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1486406604, Refined EndEsecs = 1486411694 _readLogr(): Reading videologr data from 2017037datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1486406604 & 1486411694 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1486411701, self->{EndEsecs} = 1486411694 _readLogr(): #LOGHOST_SYSTEM_UTC = 508 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1486406610 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1486411690 bin\geopdvc.pl: expdid = 5811 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 = 037 (looping to 037) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017037vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017037vnta.txt esecs, east, north, lat, lon, head, pres 1486408425, 0, 0, 36.794003, -121.851342, 343.3, 151.62 1486408925, 0, 0, 36.793935, -121.851640, 264.1, 207.47 1486409426, 0, 0, 36.793853, -121.851733, 148.7, 210.37 1486409927, 0, 0, 36.793165, -121.852595, 243.3, 112.21 rovNav(): 037: 2445 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017037vnta.txt 2445 in tList 2445 in {self->{rovT}} 2445 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1486408005 & 1486408920 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 158.63123911684 rovGDCListRefs(): dStats->sample_range = 206.98975357872 rovGDCListRefs(): returning (1st elements): 1486407925 36.800950 -121.857022 342.1 2.58076098425149 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 037 (looping to 037) Does file exist?: \\atlas\RovNavEdit\2017\vnta\nav2017037vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2017\vnta\nav2017037vntaedited.txt esecs, east, north, lat, lon, head, pres 1486408425, 0, 0, 36.794003, -121.851342, 343.3, 151.62 1486408925, 0, 0, 36.793935, -121.851640, 264.1, 207.47 1486409426, 0, 0, 36.793853, -121.851733, 148.7, 210.37 1486409927, 0, 0, 36.793165, -121.852595, 243.3, 112.21 rovNav(): 037: 2445 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017037vntaedited.txt 2445 in tList 2445 in {self->{rovT}} 2445 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1486408005 & 1486408920 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXX.....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/037/accesses.txt at bin\geopdvc.pl line 2382 ...............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 158.63123911684 rovGDCListRefs(): dStats->sample_range = 206.98975357872 rovGDCListRefs(): returning (1st elements): 1486407925 36.800950 -121.857022 342.1 2.58076098425149 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79386800 hash{lon} = -121.85172200 latMin = 30.793868 latMax = 42.793868 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.793868 AND 42.793868 AND Longitude BETWEEN -127.851722 AND -115.851722 Dive lat = 36.79386800, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.85172200 & lat = 36.79386800 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4000rov_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/vnta4000lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 508 shipNav(): $#{$self->{shipLat}}[last] = 36.792683 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 = 2446 makeTracks(): Looping through 508 point of ship position data... makeTracks(): posSubsample = 2 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4000tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4000mergedCtdNav.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 '2/6/2017 7:05:24 PM' AND '2/6/2017 7:46:14 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '2/6/2017 7:05:24 PM' AND '2/6/2017 7:46:14 PM') _findIndex(): LooframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/037/accesses.txt at bin\geopdvc.pl line 625 king for epoch secs 1486408619 ... Found time 1486408622 after 7 iterations. >>> p = 696: annotations(): 696, 1486408619, 02/06/17 19:16:59, V4000-01HD, 00:57:16:24, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4000/00_57_16_24.jpg, 158.559997558594, descend, ventana, u, 20065774 _findIndex(): Looking for epoch secs 1486408627 ... Found time 1486408632 after 6 iterations. >>> p = 706: annotations(): 706, 1486408627, 02/06/17 19:17:07, V4000-01HD, 00:57:24:09, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4000/00_57_24_09.jpg, 158.899993896484, descend, ventana, u, 20065775 _findIndex(): Looking for epoch secs 1486409515 ... Found time 1486409511 after 6 iterations. >>> p = 1584: annotations(): 1584, 1486409515, 02/06/17 19:31:55, V4000-01HD, 01:12:11:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4000/01_12_11_12.jpg, 205.889999389648, descend, ventana, u, 20065776 _findIndex(): Looking for epoch secs 1486409517 ... Found time 1486409520 after 7 iterations. >>> p = 1593: annotations(): 1593, 1486409517, 02/06/17 19:31:57, V4000-01HD, 01:12:13:27, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4000/01_12_13_27.jpg, 205.330001831055, descend, ventana, u, 20065777 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4000anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 161.1 db", "T: 10.171 C", "S: 33.662", "O: 3.507 ml/l", "L: 70.53 %"] placeAnnotations(): Placing annotation 4: Ancillary data = ["", "P: 207.4 db", "T: 9.296 C", "S: 33.84", "O: 2.595 ml/l", "L: 75.42 %"] inline_name = vnta4000anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 161.2 db", "T: 10.177 C", "S: 33.66", "O: 3.512 ml/l", "L: 70.3 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 161.1 db", "T: 10.171 C", "S: 33.662", "O: 3.507 ml/l", "L: 70.53 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 211.1 db", "T: 9.219 C", "S: 33.85", "O: 2.559 ml/l", "L: 75.46 %"] placeAnnotations(): Placing annotation 4: Ancillary data = ["", "P: 207.4 db", "T: 9.296 C", "S: 33.84", "O: 2.595 ml/l", "L: 75.42 %"] inline_name = vnta4000anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4000... frameGrabs(): matched 00_57_16_24 in [IMG]00_57_16_24.jpg 2017-02-09 00:07 58K  frameGrabs(): matched 00_57_24_09 in [IMG]00_57_24_09.jpg 2017-02-09 00:07 282K  frameGrabs(): matched 01_12_11_12 in [IMG]01_12_11_12.jpg 2017-02-09 00:07 344K  frameGrabs(): matched 01_12_13_27 in [IMG]01_12_13_27.jpg 2017-02-09 00:07 343K  iList = 00_57_16_24.jpg 00_57_24_09.jpg 01_12_11_12.jpg 01_12_13_27.jpg _findIndex(): Looking for epoch secs 1486408619 ... Found time 1486408622 after 7 iterations. >>> p = 696: frameGrabs(): index = 696 EXPD::frameGrabs(): 696: Time=1486408620, Depth=159.95, Lat=36.793950, Lon=-121.851430, Pres=161.2, Temp=10.177, Sal=33.66, O2=3.512, Light=70.3 _findIndex(): Looking for epoch secs 1486408627 ... Found time 1486408632 after 6 iterations. >>> p = 706: frameGrabs(): index = 706 EXPD::frameGrabs(): 706: Time=1486408635, Depth=159.85, Lat=36.793922, Lon=-121.851430, Pres=161.1, Temp=10.171, Sal=33.662, O2=3.507, Light=70.53 _findIndex(): Looking for epoch secs 1486409515 ... Found time 1486409511 after 6 iterations. >>> p = 1584: frameGrabs(): index = 1584 EXPD::frameGrabs(): 1584: Time=1486409505, Depth=209.44, Lat=36.793855, Lon=-121.851720, Pres=211.1, Temp=9.219, Sal=33.85, O2=2.559, Light=75.46 _findIndex(): Looking for epoch secs 1486409517 ... Found time 1486409520 after 7 iterations. >>> p = 1593: frameGrabs(): index = 1593 EXPD::frameGrabs(): 1593: Time=1486409520, Depth=205.77, Lat= 36.793857, Lon=-121.851757, Pres=207.4, Temp=9.296, Sal=33.84, O2=2.595, Light=75.42 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_57_16_24.jpg 1486408619 fw= 0.0 cm XplaceImages(): indx = 696, geoPosition = 36.793950, -121.851430, -158.695708168688, rovDepth = 158.695708168688 inline_name = vnta4000fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 00_57_16_24.jpg 1486408619 fw= 0.0 cm XplaceImages(): indx = 696, geoPosition = 36.793950, -121.851430, -158.695708168688, rovDepth = 158.695708168688 placeImages(): Placing image 2 01_12_11_12.jpg 1486409515 fw= 0.0 cm XplaceImages(): indx = 1584, geoPosition = 36.793855, -121.851720, -206.912976161106, rovDepth = 206.912976161106 inline_name = vnta4000fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 00_57_16_24.jpg 1486408619 fw= 0.0 cm XplaceImages(): indx = 696, geoPosition = 36.793950, -121.851430, -158.695708168688, rovDepth = 158.695708168688 placeImages(): Placing image 1 00_57_24_09.jpg 1486408627 fw= 0.0 cm XplaceImages(): indx = 706, geoPosition = 36.793922, -121.851430, -158.457668288277, rovDepth = 158.457668288277 placeImages(): Placing image 2 01_12_11_12.jpg 1486409515 fw= 0.0 cm .placeImages(): indx = 1584, geoPosition = 36.793855, -121.851720, -206.912976161106, rovDepth = 206.912976161106 placeImages(): Placing image 3 01_12_13_27.jpg 1486409517 fw= 0.0 cm .placeImages(): indx = 1593, geoPosition = 36.793857, -121.851757, -204.265388054822, rovDepth = 204.265388054822 inline_name = vnta4000fg01.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 '2/6/2017 7:05:24 PM' AND '2/6/2017 7:46:14 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2017/vnta/vnta4000.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5811,'/3Dreplay/3D.asp?dives=vnta4000','vnta4000','','','',4000 Executing sql: UPDATE Dive SET DiveLatMid = 36.7939, DiveLonMid = -121.8517, DiveDepthMid = -158.6 WHERE RovName = 'vnta' AND DiveNumber = 4000