Running bin\geopdvc.pl at Thu Apr 6 03:00:11 2017 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 4001 bin\geopdvc.pl: Getting Expedition object for diveno = 4001 and rname = vnta set_expd(): rov = vnta, diveno = 4001 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 = 4001 AND RovName = 'vnta' RS->Fields(0)->Value= 5811 RS->Fields(1)->Value= 1486413430 RS->Fields(2)->Value= 1486417694 RS->Fields(3)->Value= 2/6/2017 8:37:10 PM RS->Fields(4)->Value= 2/6/2017 9:48:14 PM set_expd(): $self->{EndDDD} = 037 set_expd(): $self->{StartEsecs} = 1486412230 set_expd(): $self->{EndEsecs} = 1486418894 set_expd(): $self->{EndDtg} = 2/6/2017 9:48:14 PM _readLogr(): Reading videologr data from 2017037datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1486412230 & 1486418894 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1486418902, self->{EndEsecs} = 1486418894 _readLogr(): #LOGHOST_SYSTEM_UTC = 666 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1486412231 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1486418892 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1486412231 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1486418892 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1486412110, Refined EndEsecs = 1486419014 _readLogr(): Reading videologr data from 2017037datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1486412110 & 1486419014 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1486419022, self->{EndEsecs} = 1486419014 _readLogr(): #LOGHOST_SYSTEM_UTC = 690 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1486412111 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1486419012 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 1486413485, 0, 0, 36.793057, -121.849698, 314.2, 13.96 1486413986, 0, 0, 36.793183, -121.849888, 118.6, 138.01 1486414487, 0, 0, 36.793035, -121.849242, 131.5, 217.95 1486414988, 0, 0, 36.792643, -121.849203, 208.3, 239.72 1486415488, 0, 0, 36.792603, -121.849212, 277.6, 208.50 1486415989, 0, 0, 36.792822, -121.848972, 238.7, 241.43 1486416490, 0, 0, 36.792818, -121.848987, 220.3, 242.76 1486416991, 0, 0, 36.792067, -121.848882, 239.1, 171.84 1486417492, 0, 0, 36.791732, -121.849178, 231.9, 10.09 rovNav(): 037: 4255 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017037vnta.txt 4255 in tList 4255 in {self->{rovT}} 4255 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1486417515 & 1486416315 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 210.795070773909 rovGDCListRefs(): dStats->sample_range = 239.650051197634 rovGDCListRefs(): returning (1st elements): 1486413432 36.793692 -121.852005 315.4 2.68002208904972 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 1486413485, 0, 0, 36.793057, -121.849698, 314.2, 13.96 1486413986, 0, 0, 36.793183, -121.849888, 118.6, 138.01 1486414487, 0, 0, 36.793035, -121.849242, 131.5, 217.95 1486414988, 0, 0, 36.792643, -121.849203, 208.3, 239.72 1486415488, 0, 0, 36.792603, -121.849212, 277.6, 208.50 1486415989, 0, 0, 36.792822, -121.848972, 238.7, 241.43 1486416490, 0, 0, 36.792818, -121.848987, 220.3, 242.76 1486416991, 0, 0, 36.792067, -121.848882, 239.1, 171.84 1486417492, 0, 0, 36.791740, -121.849194, 231.9, 10.09 rovNav(): 037: 4255 in tList after reading from \\atlas\RovNavEdit\2017\vnta\nav2017037vntaedited.txt 4255 in tList 4255 in {self->{rovT}} 4255 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1486417515 & 1486416315 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXXXXXXXXXXXXX.............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................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 = 210.795070773909 rovGDCListRefs(): dStats->sample_range = 239.650051199626 rovGDCListRefs(): returning (1st elements): 1486413432 36.793692 -121.852005 315.4 2.68002208904972 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.79282300 hash{lon} = -121.84900400 latMin = 30.792823 latMax = 42.792823 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.792823 AND 42.792823 AND Longitude BETWEEN -127.849004 AND -115.849004 Dive lat = 36.79282300, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -121.84900400 & lat = 36.79282300 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta4001rov_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/vnta4001lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 690 shipNav(): $#{$self->{shipLat}}[last] = 36.797716 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 = 4256 makeTracks(): Looping through 690 point of ship position data... makeTracks(): posSubsample = 4 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta4001tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2017/vnta/vnta4001mergedCtdNav.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 8:37:10 PM' AND '2/6/2017 9:48:14 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '2/6/2017 8:37:10 PM' AND '2/6/2017 9:48:14 PM') _findIndex(): Looking for epoch secs 1486414245 ... Found time 1486414246 after 7 iterations. >>> p = 813: annotations(): 813, 1486414245, 02/06/17 20:50:45, V4001-01HD, 00:26:25:25, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/00_26_25_25.jpg, 178.380004882813, descend, ventana, u, 20065778 _findIndex(): Looking for epoch secs 1486416245 ... Found time 1486416245 after 7 iterations. >>> p = 2808: annotations(): 2808, 1486416245, 02/06/17 21:24:05, V4001-01HD, 00:59:43:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/00_59_43_10.jpg, 240.880004882813, descend, ventana, u, 20065779 _findIndex(): Looking for epoch secs 1486416320 ... Found time 1486416319 after 8 iterations. >>> p = 2882: annotations(): 2882, 1486416320, 02/06/17 21:25:20, V4001-01HD, 01:00:58:08, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/01_00_58_08.jpg, 240.699996948242, descend, ventana, u, 20065780 _findIndex(): Looking for epoch secs 1486416368 ... Found time 1486416370 after 8 iterations. >>> p = 2933: annotations(): 2933, 1486416368, 02/06/17 21:26:08, V4001-01HD, 01:01:46:21, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/01_01_46_21.jpg, 240.940002441406, descend, ventana, u, 20065781 _findIndex(): Looking for epoch secs 1486416376 ... Found time 1486416378 after 7 iterations. >>> p = 2941: annotations(): 2941, 1486416376, 02/06/17 21:26:16, V4001-01HD, 01:01:54:06, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/01_01_54_06.jpg, 240.880004882813, descend, ventana, u, 20065782 _findIndex(): Looking for epoch secs 1486416405 ... Found time 1486416403 after 8 iterations. >>> p = 2966: annotations(): 2966, 1486416405, 02/06/17 21:26:45, V4001-01HD, 01:02:23:05, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/01_02_23_05.jpg, 240.820007324219, descend, ventana, u, 20065783 _findIndex(): Looking for epoch secs 1486416752 ... Found time 1486416752 after 8 iterations. >>> p = 3315: annotations(): 3315, 1486416752, 02/06/17 21:32:32, V4001-01HD, 01:07:40:16, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/01_07_40_16.jpg, 217.690002441406, descend, ventana, u, 20065784 _findIndex(): Looking for epoch secs 1486416770 ... Found time 1486416769 after 8 iterations. >>> p = 3332: annotations(): 3332, 1486416770, 02/06/17 21:32:50, V4001-02HD, 01:07:59:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/01_07_59_00.jpg, 211.199996948242, descend, ventana, u, 20065785 _findIndex(): Looking for epoch secs 1486416809 ... Found time 1486416811 after 7 iterations. >>> p = 3373: annotations(): 3373, 1486416809, 02/06/17 21:33:29, V4001-02HD, 01:08:37:14, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/4001/01_08_37_14.jpg, 199.820007324219, descend, ventana, u, 20065786 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... inline_name = vnta4001anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 244.4 db", "T: 7.626 C", "S: 34.08", "O: 1.403 ml/l", "L: 62.99 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 244.5 db", "T: 7.19 C", "S: 34.137", "O: 1.163 ml/l", "L: 56.94 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 244.6 db", "T: 7.462 C", "S: 34.106", "O: 1.231 ml/l", "L: 65.14 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 217 db", "T: 8.232 C", "S: 34.002", "O: 1.786 ml/l", "frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/037/accesses.txt at bin\geopdvc.pl line 625 L: 71.79 %"] inline_name = vnta4001anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 181.1 db", "T: 9.44 C", "S: 33.813", "O: 2.756 ml/l", "L: 76.73 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 244.4 db", "T: 7.626 C", "S: 34.08", "O: 1.403 ml/l", "L: 62.99 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 244.3 db", "T: 7.535 C", "S: 34.09", "O: 1.396 ml/l", "L: 61.99 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 244.5 db", "T: 7.19 C", "S: 34.137", "O: 1.163 ml/l", "L: 56.94 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 244.5 db", "T: 7.19 C", "S: 34.137", "O: 1.163 ml/l", "L: 56.94 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 244.6 db", "T: 7.462 C", "S: 34.106", "O: 1.231 ml/l", "L: 65.14 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 222.4 db", "T: 8.121 C", "S: 34.021", "O: 1.714 ml/l", "L: 72.53 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 217 db", "T: 8.232 C", "S: 34.002", "O: 1.786 ml/l", "L: 71.79 %"] placeAnnotations(): Placing annotation 9: Ancillary data = ["", "P: 202.9 db", "T: 8.236 C", "S: 34.001", "O: 1.805 ml/l", "L: 71.31 %"] inline_name = vnta4001anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2017/vnta4001... frameGrabs(): matched 00_26_25_25 in