Running bin\geopdvc.pl at Mon Jun 6 03:00:21 2016 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3923 bin\geopdvc.pl: Getting Expedition object for diveno = 3923 and rname = vnta set_expd(): rov = vnta, diveno = 3923 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 = 3923 AND RovName = 'vnta' RS->Fields(0)->Value= 5500 RS->Fields(1)->Value= 1460152845 RS->Fields(2)->Value= 1460153580 RS->Fields(3)->Value= 4/8/2016 10:00:45 PM RS->Fields(4)->Value= 4/8/2016 10:13:00 PM set_expd(): $self->{EndDDD} = 099 set_expd(): $self->{StartEsecs} = 1460151645 set_expd(): $self->{EndEsecs} = 1460154780 set_expd(): $self->{EndDtg} = 4/8/2016 10:13:00 PM _readLogr(): Reading videologr data from 2016099datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1460151645 & 1460154780 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1460154781, self->{EndEsecs} = 1460154780 _readLogr(): #LOGHOST_SYSTEM_UTC = 312 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1460151651 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1460154771 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1460151651 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1460154771 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1460151525, Refined EndEsecs = 1460154900 _readLogr(): Reading videologr data from 2016099datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1460151525 & 1460154900 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1460154901, self->{EndEsecs} = 1460154900 _readLogr(): #LOGHOST_SYSTEM_UTC = 336 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1460151531 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1460154891 bin\geopdvc.pl: expdid = 5500 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 = 099 (looping to 099) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016099vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2016\vnta\nav2016099vnta.txt esecs, east, north, lat, lon, head, pres 1460153140, 0, 0, 36.751862, -121.838692, 195.5, 28.85 rovNav(): 099: 737 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016099vnta.txt 737 in tList 737 in {self->{rovT}} 737 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1460153580 & 1460153250 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): ...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 24.9477455833159 rovGDCListRefs(): dStats->sample_range = 26.5403366739459 rovGDCListRefs(): returning (1st elements): 1460152849 36.752105 -121.839120 138.6 2.68003194993915 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 099 (looping to 099) Does file exist?: \\atlas\RovNavEdit\2016\vnta\nav2016099vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data fframeGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/099/accesses.txt at bin\geopdvc.pl line 2382 rom \\atlas\RovNavEdit\2016\vnta\nav2016099vntaedited.txt esecs, east, north, lat, lon, head, pres 1460153140, 0, 0, 36.751854, -121.838695, 195.5, 28.85 rovNav(): 099: 737 in tList after reading from \\atlas\RovNavEdit\2016\vnta\nav2016099vntaedited.txt 737 in tList 737 in {self->{rovT}} 737 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1460153580 & 1460153250 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXX...............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 24.9477455965539 rovGDCListRefs(): dStats->sample_range = 26.5403366989389 rovGDCListRefs(): returning (1st elements): 1460152849 36.752294 -121.838917 138.6 2.68003190513402 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.75187500 hash{lon} = -121.83871300 latMin = 30.751875 latMax = 42.751875 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.751875 AND 42.751875 AND Longitude BETWEEN -127.838713 AND -115.838713 Dive lat = 36.75187500, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.83871300 & lat = 36.75187500 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3923rov_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 = geo2016/vnta/vnta3923lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 336 shipNav(): $#{$self->{shipLat}}[last] = 36.796123 Position subsampling set to 1 setROVcolorlines(): Assigning color line for Light... varColors = LightColors index0 = 0.4270 0.3616 0.0000 setROVcolorlines(): Assigning color line for O2... varColors = O2Colors index0 = 0.0000 0.1881 0.8119 setROVcolorlines(): Assigning color line for Sal... varColors = SalColors index0 = 1.0000 0.8500 0.0000 setROVcolorlines(): Assigning color line for Temp... varColors = TempColors index0 = 0.1620 0.8380 0.0000 makeTracks(): n = 738 makeTracks(): Looping through 336 point of ship position data... makeTracks(): posSubsample = 1 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3923tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2016/vnta/vnta3923mergedCtdNav.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(frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/099/accesses.txt at bin\geopdvc.pl line 625 Observer, '') AS Observer, AnnotationMode, ObservationID_FK AS ObservationID FROM dbo.Annotations WHERE (ShipName = '') AND (RecordedDate BETWEEN '4/8/2016 10:00:45 PM' AND '4/8/2016 10:13:00 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '4/8/2016 10:00:45 PM' AND '4/8/2016 10:13:00 PM') _findIndex(): Looking for epoch secs 1460152976 ... Found time 1460152975 after 5 iterations. >>> p = 126: annotations(): 126, 1460152976, 04/08/16 22:02:56, V3923-01HD, 03:11:17:22, wave height sensor, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3923/03_11_17_22.jpg, 24.3500003814697, cruise, lonny, u, 20061362 _findIndex(): Looking for epoch secs 1460153118 ... Found time 1460153113 after 5 iterations. >>> p = 264: annotations(): 264, 1460153118, 04/08/16 22:05:18, V3923-01HD, 03:11:17:01, Actinopteri, , 27.7700004577637, cruise, lonny, u, 5025798 _findIndex(): Looking for epoch secs 1460153121 ... Found time 1460153125 after 2 iterations. >>> p = 276: annotations(): 276, 1460153121, 04/08/16 22:05:21, V3923-01HD, 03:11:19:25, ripples, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3923/03_11_19_25.jpg, 27.9300003051758, cruise, lonny, u, 5025799 _findIndex(): Looking for epoch secs 1460153435 ... Found time 1460153436 after 5 iterations. >>> p = 586: annotations(): 586, 1460153435, 04/08/16 22:10:35, V3923-01HD, 03:16:32:23, water, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3923/03_16_32_23.jpg, 16.4699993133545, ascend, lonny, u, 5025800 _findIndex(): Looking for epoch secs 1460153129 ... Found time 1460153125 after 2 iterations. >>> p = 276: annotations(): 276, 1460153129, 04/08/16 22:05:29, V3923-01HD, 03:13:50:18, wave height sensor, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3923/03_13_50_18.jpg, 28.6200008392334, cruise, lonny, u, 20061363 _findIndex(): Looking for epoch secs 1460153137 ... Found time 1460153136 after 5 iterations. >>> p = 287: annotations(): 287, 1460153137, 04/08/16 22:05:37, V3923-01HD, 03:13:58:03, wave height sensor, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3923/03_13_58_03.jpg, 28, cruise, lonny, u, 20061364 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta3923anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: ripples Ancillary data = ["ripples", "P: 30 db", "T: 10.809 C", "S: 33.565", "O: 4.236 ml/l", "L: 75.14 %"] placeAnnotations(): Placing annotation 4: wave height sensor Ancillary data = ["wave height sensor", "P: 21.3 db", "T: 11.598 C", "S: 33.508", "O: 5.079 ml/l", "L: 75.24 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 30.6 db", "T: 10.8 C", "S: 33.567", "O: 4.231 ml/l", "L: 74.35 %"] inline_name = vnta3923anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: Actinopteri Ancillary data = ["Actinopteri", "P: 26.5 db", "T: 11.8 C", "S: 33.458", "O: 5.923 ml/l", "L: 76.13 %"] placeAnnotations(): Placing annotation 2: ripples Ancillary data = ["ripples", "P: 30 db", "T: 10.809 C", "S: 33.565", "O: 4.236 ml/l", "L: 75.14 %"] placeAnnotations(): Placing annotation 3: water Ancillary data = ["water", "P: 30.6 db", "T: 10.8 C", "S: 33.567", "O: 4.231 ml/l", "L: 74.35 %"] placeAnnotations(): Placing annotation 4: wave height sensor Ancillary data = ["wave height sensor", "P: 21.3 db", "T: 11.598 C", "S: 33.508", "O: 5.079 ml/l", "L: 75.24 %"] placeAnnotations(): Placing annotation 5: wave height sensor Ancillary data = ["wave height sensor", "P: 30.6 db", "T: 10.8 C", "S: 33.567", "O: 4.231 ml/l", "L: 74.35 %"] placeAnnotations(): Placing annotation 6: Ancillary data = ["", "P: 30.6 db", "T: 10.8 C", "S: 33.567", "O: 4.231 ml/l", "L: 74.35 %"] inline_name = vnta3923anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2016/vnta3923... frameGrabs(): matched 03_11_17_22 in [IMG]03_11_17_22.jpg28-May-2016 00:10 299K frameGrabs(): matched 03_11_19_25 in [IMG]03_11_19_25.jpg28-May-2016 00:10 307K frameGrabs(): matched 03_13_50_18 in [IMG]03_13_50_18.jpg28-May-2016 00:10 332K frameGrabs(): matched 03_13_58_03 in [IMG]03_13_58_03.jpg28-May-2016 00:10 317K frameGrabs(): matched 03_16_32_23 in [IMG]03_16_32_23.jpg28-May-2016 00:10 264K iList = 03_11_17_22.jpg 03_11_19_25.jpg 03_13_50_18.jpg 03_13_58_03.jpg 03_16_32_23.jpg _findIndex(): Looking for epoch secs 1460152976 ... Found time 1460152975 after 5 iterations. >>> p = 126: frameGrabs(): index = 126 EXPD::frameGrabs(): 126: Time=1460152980, Depth=26.3, Lat=36.752441, Lon=-121.838821, Pres=26.5, Temp=11.8, Sal=33.458, O2=5.923, Light=76.13 _findIndex(): Looking for epoch secs 1460153121 ... Found time 1460153125 after 2 iterations. >>> p = 276: frameGrabs(): index = 276 EXPD::frameGrabs(): 276: Time=1460153130, Depth=30.37, Lat=36.751854, Lon=-121.838693, Pres=30.6, Temp=10.8, Sal=33.567, O2=4.231, Light=74.35 _findIndex(): Looking for epoch secs 1460153129 ... Found time 1460153125 after 2 iterations. >>> p = 276: frameGrabs(): index = 276 EXPD::frameGrabs(): 276: Time=1460153130, Depth=30.37, Lat=36.751854, Lon=-121.838693, Pres=30.6, Temp=10.8, Sal=33.567, O2=4.231, Light=74.35 _findIndex(): Looking for epoch secs 1460153137 ... Found time 1460153136 after 5 iterations. >>> p = 287: frameGrabs(): index = 287 EXPD::frameGrabs(): 287: Time=1460153130, Depth=30.37, Lat=36.751854, Lon=-121.838695, Pres=30.6, Temp=10.8, Sal=33.567, O2=4.231, Light=74.35 _findIndex(): Looking for epoch secs 1460153435 ... Found time 1460153436 after 5 iterations. >>> p = 586: frameGrabs(): index = 586 EXPD::frameGrabs(): 586: Time=1460153430, Depth=21.14, Lat=36.751872, Lon=-121.838742, Pres=21.3, Temp=11.598, Sal=33.508, O2=5.079, Light=75.24 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 03_11_17_22.jpg 1460152976 fw= 0.0 cm XplaceImages(): indx = 126, geoPosition = 36.752441, -121.838821, -23.9403659917097, rovDepth = 23.9403659917097 inline_name = vnta3923fg10.wrl bin\geopdvc.pl: Calling placeImages with every 2 image... placeImages(): Placing image 0 03_11_17_22.jpg 1460152976 fw= 0.0 cm XplaceImages(): indx = 126, geoPosition = 36.752441, -121.838821, -23.9403659917097, rovDepth = 23.9403659917097 placeImages(): Placing image 2 03_13_50_18.jpg 1460153129 fw= 0.0 cm .placeImages(): indx = 276, geoPosition = 36.751854, -121.838693, -28.5752618589545, rovDepth = 28.5752618589545 placeImages(): Placing image 4 03_16_32_23.jpg 1460153435 fw= 0.0 cm XplaceImages(): indx = 586, geoPosition = 36.751872, -121.838742, -15.1268285832176, rovDepth = 15.1268285832176 inline_name = vnta3923fg02.wrl bin\geopdvc.pl: Calling placeImages with every 1 image... placeImages(): Placing image 0 03_11_17_22.jpg 1460152976 fw= 0.0 cm XplaceImages(): indx = 126, geoPosition = 36.752441, -121.838821, -23.9403659917097, rovDepth = 23.9403659917097 placeImages(): Placing image 1 03_11_19_25.jpg 1460153121 fw= 0.0 cm .placeImages(): indx = 276, geoPosition = 36.751854, -121.838693, -28.5752618589545, rovDepth = 28.5752618589545 placeImages(): Placing image 2 03_13_50_18.jpg 1460153129 fw= 0.0 cm .placeImages(): indx = 276, geoPosition = 36.751854, -121.838693, -28.5752618589545, rovDepth = 28.5752618589545 placeImages(): Placing image 3 03_13_58_03.jpg 1460153137 fw= 0.0 cm .placeImages(): indx = 287, geoPosition = 36.751854, -121.838695, -28.1584234108212, rovDepth = 28.1584234108212 placeImages(): Placing image 4 03_16_32_23.jpg 1460153435 fw= 0.0 cm XplaceImages(): indx = 586, geoPosition = 36.751872, -121.838742, -15.1268285832176, rovDepth = 15.1268285832176 inline_name = vnta3923fg01.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/8/2016 10:00:45 PM' AND '4/8/2016 10:13:00 PM') AND ROVName = 'Ventana' ORDER BY CollectionEventDTG bin\geopdvc.pl: World = geo2016/vnta/vnta3923.wrl Adding record to ExpeditionData table... Executing sql: exec insertExpeditionData '3Dreplay','','',5500,'/3Dreplay/3D.asp?dives=vnta3923','vnta3923','','','',3923 Executing sql: UPDATE Dive SET DiveLatMid = 36.7519, DiveLonMid = -121.8387, DiveDepthMid = -24.9 WHERE RovName = 'vnta' AND DiveNumber = 3923