Running bin\geopdvc.pl at Sun Nov 24 03:00:04 2013 on host solstice Command line: bin\geopdvc.pl -rov vnta -dive 3737 bin\geopdvc.pl: Getting Expedition object for diveno = 3737 and rname = vnta set_expd(): rov = vnta, diveno = 3737 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 = 3737 AND RovName = 'vnta' RS->Fields(0)->Value= 4858 RS->Fields(1)->Value= 1380213307 RS->Fields(2)->Value= 1380215848 RS->Fields(3)->Value= 9/26/2013 4:35:07 PM RS->Fields(4)->Value= 9/26/2013 5:17:28 PM set_expd(): $self->{EndDDD} = 269 set_expd(): $self->{StartEsecs} = 1380212107 set_expd(): $self->{EndEsecs} = 1380217048 set_expd(): $self->{EndDtg} = 9/26/2013 5:17:28 PM _readLogr(): Reading videologr data from 2013269datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1380212107 & 1380217048 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1380217054, self->{EndEsecs} = 1380217048 _readLogr(): #LOGHOST_SYSTEM_UTC = 493 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1380212113 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1380217044 set_expd(): Calling _refineStartAndEndEsecs... refineStartAndEndEsecs(): LOGHOST_SYSTEM_UTC[0] = 1380212113 refineStartAndEndEsecs(): $LOGHOST_SYSTEM_UTC[end] = 1380217044 refineStartAndEndEsecs(): numMinBack = 22, numMinForward = 22 refineStartAndEndEsecs(): Refined StartEsecs = 1380211987, Refined EndEsecs = 1380217168 _readLogr(): Reading videologr data from 2013269datavislogr.dat.gz. _readLogr(): Expedition start & stop times are 1380211987 & 1380217168 _readLogr(): Flushing variable names: _readLogr(): esecs of file line[0] = 1380217174, self->{EndEsecs} = 1380217168 _readLogr(): #LOGHOST_SYSTEM_UTC = 517 _readLogr(): $LOGHOST_SYSTEM_UTC[beg] = 1380211993 _readLogr(): $LOGHOST_SYSTEM_UTC[end] = 1380217164 bin\geopdvc.pl: expdid = 4858 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 = 269 (looping to 269) Does file exist?: \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2013\vnta\nav2013269vnta.txt esecs, east, north, lat, lon, head, pres 1380213576, 0, 0, 36.788303, -122.057213, 326.8, 115.02 1380214077, 0, 0, 36.788235, -122.057628, 35.5, 204.75 1380214578, 0, 0, 36.788625, -122.057632, 149.6, 204.59 1380215078, 0, 0, 36.789017, -122.056800, 57.3, 166.66 1380215579, 0, 0, 36.788837, -122.056280, 335.9, 40.09 1380216080, 0, 0, 36.788703, -122.055728, 324.1, 3.46 rovNav(): 269: 3075 in tList after reading from \\atlas\RovNavEdit\2013\vnta\nav2013269vnta.txt 3075 in tList 3075 in {self->{rovT}} 3075 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1380213645 & 1380214605 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): .............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 133.47187073243 rovGDCListRefs(): dStats->sample_range = 201.635200816135 rovGDCListRefs(): returning (1st elements): 1380212553 36.696737 -122.043183 12.7 2.69989712579815 geopdcv.pl(): editedNav flag = 1 Using mbnavedit edited Navigation. rovnavCTDO(): calcUTM = 0 rovNav(): 1, calcUTM = 0 rovNav(): calcUTM = 0 rovNav(): DDD = 269 (looping to 269) Does file exist?: \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt yes. EXPD.pm: editNav flag = rovNav(): Opening ROV nav data from \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt esecs, east, north, lat, lon, head, pres 1380213576, 0, 0, 36.788273, -122.057234, 326.8, 115.02 1380214077, 0, 0, 36.788563, -122.057527, 35.5, 204.75 1380214578, 0, 0, 36.788652, -122.057557, 149.6, 204.59 1380215078, 0, 0, 36.788964, -122.056864, 57.3, 166.66 1380215579, 0, 0, 36.788837, -122.056280, 335.9, 40.09 1380216080, 0, 0, 36.788703, -122.055728, 324.1, 3.46 rovNav(): 269: 3075 in tList after reading from \\atlas\RovNavEdit\2013\vnta\nav2013269vntaedited.txt 3075 in tList 3075 in {self->{rovT}} 3075 in {self->{rovH}} rovCTDO(): ROV CTDO start & stop times are 1380213645 & 1380214605 rovNavCTDO(): Looking up ROVCTDO data ('.'found, 'X' exceeds max iter.): XXXXXXXXXXXXXXXX..........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2013/269/accesses.txt at bin\geopdvc.pl line 2382 ...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................XXXXXXXX rovGDCListRefs(): dMedian = 133.47187073243 rovGDCListRefs(): dStats->sample_range = 201.635200816135 rovGDCListRefs(): returning (1st elements): 1380212553 36.788605 -122.057195 12.7 2.69987518802756 bin\geopdvc.pl: ------------------------------------------------------------------------------- hash{lat} = 36.78863600 hash{lon} = -122.05719500 latMin = 30.788636 latMax = 42.788636 Executing sql: SELECT Area, Latitude, Longitude FROM GeoOrigin WHERE Latitude BETWEEN 30.788636 AND 42.788636 AND Longitude BETWEEN -128.057195 AND -116.057195 Dive lat = 36.78863600, area = cencal bin\geopdvc.pl: Found area = cencal in GeoOrigin For midpoint lon = -122.05719500 & lat = 36.78863600 Using olon = -122 & olat = 36 bin\geopdvc.pl: Calling makeROVorig... inline_name = vnta3737rov_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 = geo2013/vnta/vnta3737lookup.wrl bin\geopdvc.pl: Getting ship nav... shipNav(): selecting for ShipName = rcsn shipNav(): WARNING: no match for ShipName = rcsn shipNav(): $#{$self->{shipLat}} = 517 shipNav(): $#{$self->{shipLat}}[last] = 36.782833 Position subsampling set to 3 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 = 3076 makeTracks(): Looping through 517 point of ship position data... makeTracks(): posSubsample = 3 makeTracks(): Writing tracks file... makeTracks(): INFO $sname = inline_name = vnta3737tracks.wrl writeCtdPosFile(): Writing merged CTD Nav data to geo2013/vnta/vnta3737mergedCtdNav.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/26/2013 4:35:07 PM' AND '9/26/2013 5:17:28 PM') OR (RovName = 'Ventana') AND (RecordedDate BETWEEN '9/26/2013 4:35:07 PM' AND '9/26/2013 5:17:28 PM') _findIndex(): Looking for epoch secs 1380213795 ... Found time 1380213797 after 5 iterations. >>> p = 720: annotations(): 720, 1380213795, 09/26/13 16:43:15, V3737-01HD, 05:27:10:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_27_10_12.jpg, 204.139999389648, descend, wape, u, 20052161 _findIndex(): Looking for epoch secs 1380213795 ... Found time 1380213797 after 5 iterations. >>> p = 720: annotations(): 720, 1380213795, 09/26/13 16:43:15, V3737-01HD, 05:27:20:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_27_20_12.jpg, 204.139999389648, descend, wape, u, 20052162 _findIndex(): Looking for epoch secs 1380213795 ... Found time 1380213797 after 5 iterations. >>> p = 720: annotations(): 720, 1380213795, 09/26/13 16:43:15, V3737-01HD, 05:27:30:27, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_27_30_27.jpg, 204.139999389648, descend, wape, u, 20052163 _findIndex(): Looking for epoch secs 1380213795 ... Found time 1380213797 after 5 iterations. >>> p = 720: annotations(): 720, 1380213795, 09/26/13 16:43:15, V3737-01HD, 05:27:54:00, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_27_54_00.jpg, 204.139999389648, descend, wape, u, 20052164 _findIndex(): Looking for epoch secs 1380213797 ... Found time 1380213797 after 5 iterations. >>> p = 720: annotations(): 720, 1380213797, 09/26/13 16:43:17, V3737-01HD, 05:28:04:03, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_28_04_03.jpg, 204.139999389648, descend, wape, u, 20052165 _findIndex(): Looking for epoch secs 1380213825 ... Found time 1380213821 after 6 iterations. >>> p = 744: annotations(): 744, 1380213825, 09/26/13 16:43:45, V3737-01HD, 05:28:31:17, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_28_31_17.jpg, 204, descend, wape, u, 20052166 _findIndex(): Looking for epoch secs 1380213980 ... Found time 1380213978 after 7 iterations. >>> p = 900: annotations(): 900, 1380213980, 09/26/13 16:46:20, V3737-01HD, 05:31:06:28, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_31_06_28.jpg, 201.759994506836, descend, wape, u, 20052167 _findIndex(): Looking for epoch secs 1380214018 ... Found time 1380214014 after 6 iterations. >>> p = 936: annotations(): 936, 1380214018, 09/26/13 16:46:58, V3737-01HD, 05:31:45:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_31_45_12.jpg, 203.119995117188, descend, wape, u, 20052168 _findIndex(): Looking for epoch secs 1380214204 ... Found time 1380214206 after 6 iterations. >>> p = 1128: annotations(): 1128, 1380214204, 09/26/13 16:50:04, V3737-01HD, 05:34:50:21, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_34_50_21.jpg, 203.309997558594, descend, wape, u, 20052169 _findIndex(): Looking for epoch secs 1380214277 ... Found time 1380214278 after 5 iterations. >>> p = 1200: annotations(): 1200, 1380214277, 09/26/13 16:51:17, V3737-01HD, 05:36:04:04, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_36_04_04.jpg, 203.330001831055, descend, wape, u, 20052170 _findIndex(): Looking for epoch secs 1380214380 ... Found time 1380214380 after 8 iterations. >>> p = 1302: annotations(): 1302, 1380214380, 09/26/13 16:53:00, V3737-01HD, 05:37:46:01, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_37_46_01.jpg, 202.979995727539, descend, wape, u, 20052171 _findIndex(): Looking for epoch secs 1380214464 ... Found time 1380214464 after 8 iterations. >>> p = 1386: annotations(): 1386, 1380214464, 09/26/13 16:54:24, V3737-01HD, 05:39:09:28, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_39_09_28.jpg, 202.960006713867, descend, wape, u, 20052172 _findIndex(): Looking for epoch secs 1380214493 ... Found time 1380214494 after 6 iterations. >>> p = 1416: annotations(): 1416, 1380214493, 09/26/13 16:54:53, V3737-01HD, 05:39:39:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_39_39_12.jpg, 202.940002441406, descend, wape, u, 20052173 _findIndex(): Looking for epoch secs 1380214503 ... Found time 1380214506 after 7 iterations. >>> p = 1428: annotations(): 1428, 1380214503, 09/26/13 16:55:03, V3737-01HD, 05:39:49:12, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_39_49_12.jpg, 202.860000610352, descend, wape, u, 20052174 _findIndex(): Looking for epoch secs 1380214572 ... Found time 1380214567 after 5 iterations. >>> p = 1488: annotations(): 1488, 1380214572, 09/26/13 16:56:12, V3737-01HD, 05:40:58:10, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_40_58_10.jpg, 202.979995727539, descend, wape, u, 20052175 _findIndex(): Looking for epoch secs 1380214735 ... Found time 1380214736 after 6 iterations. >>> p = 1657: annotations(): 1657, 1380214735, 09/26/13 16:58:55, V3737-01HD, 05:43:40:20, physical object, http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/images/3737/05_43_40_20.jpg, 202.960006713867, descend, wape, u, 20052176 bin\geopdvc.pl: Calling placeAnnotations with every 10 annotation... placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 201.4 db", "T: 9.099 C", "S: 34.006", "O: 1.537 ml/l", "L: 90.42 %"] inline_name = vnta3737anno10.wrl bin\geopdvc.pl: Calling placeAnnotations with every 2 annotation... placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 202.3 db", "T: 9.082 C", "S: 34.009", "O: 1.533 ml/l", "L: 90.55 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 202.3 db", "T: 9.082 C", "S: 34.009", "O: 1.533 ml/l", "L: 90.55 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 202.1 db", "T: 9.104 C", "S: 34.003", "O: 1.544 ml/l", "L: 90.62 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 201.3 db", "T: 9.106 C", "S: 34.004", "O: 1.54 ml/l", "L: 90.51 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 201.4 db", "T: 9.099 C", "S: 34.006", "O: 1.537 ml/l", "L: 90.42 %"] placeAnnotations(): Placing annotation 12: physical object Ancillary data = ["physical object", "P: 201.1 db", "T: 9.096 C", "S: 34.006", "O: 1.532 ml/l", "L: 88.43 %"] placeAnnotations(): Placing annotation 14: physical object Ancillary data = ["physical object", "P: 201.1 db", "T: 9.105 C", "S: 34.004", "O: 1.535 ml/l", "L: 89.1 %"] placeAnnotations(): Placing annotation 16: Ancillary data = ["", "P: 201.1 db", "T: 9.114 C", "S: 33.999", "O: 1.544 ml/l", "L: 88.02 %"] inline_name = vnta3737anno02.wrl bin\geopdvc.pl: Calling placeAnnotations with every 1 annotation... placeAnnotations(): Placing annotation 1: physical object Ancillary data = ["physical object", "P: 202.3 db", "T: 9.082 C", "S: 34.009", "O: 1.533 ml/l", "L: 90.55 %"] placeAnnotations(): Placing annotation 2: physical object Ancillary data = ["physical object", "P: 202.3 db", "T: 9.082 C", "S: 34.009", "O: 1.533 ml/l", "L: 90.55 %"] placeAnnotations(): Placing annotation 3: physical object Ancillary data = ["physical object", "P: 202.3 db", "T: 9.082 C", "S: 34.009", "O: 1.533 ml/l", "L: 90.55 %"] placeAnnotations(): Placing annotation 4: physical object Ancillary data = ["physical object", "P: 202.3 db", "T: 9.082frameGrabs(): Can't get http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2013/269/accesses.txt at bin\geopdvc.pl line 625 C", "S: 34.009", "O: 1.533 ml/l", "L: 90.55 %"] placeAnnotations(): Placing annotation 5: physical object Ancillary data = ["physical object", "P: 202.3 db", "T: 9.082 C", "S: 34.009", "O: 1.533 ml/l", "L: 90.55 %"] placeAnnotations(): Placing annotation 6: physical object Ancillary data = ["physical object", "P: 202.1 db", "T: 9.104 C", "S: 34.003", "O: 1.544 ml/l", "L: 90.62 %"] placeAnnotations(): Placing annotation 7: physical object Ancillary data = ["physical object", "P: 199.9 db", "T: 9.1 C", "S: 34.004", "O: 1.543 ml/l", "L: 90.52 %"] placeAnnotations(): Placing annotation 8: physical object Ancillary data = ["physical object", "P: 201.3 db", "T: 9.106 C", "S: 34.004", "O: 1.54 ml/l", "L: 90.51 %"] placeAnnotations(): Placing annotation 9: physical object Ancillary data = ["physical object", "P: 201.4 db", "T: 9.123 C", "S: 34.001", "O: 1.543 ml/l", "L: 90.33 %"] placeAnnotations(): Placing annotation 10: physical object Ancillary data = ["physical object", "P: 201.4 db", "T: 9.099 C", "S: 34.006", "O: 1.537 ml/l", "L: 90.42 %"] placeAnnotations(): Placing annotation 11: physical object Ancillary data = ["physical object", "P: 201.1 db", "T: 9.115 C", "S: 34.002", "O: 1.543 ml/l", "L: 90.33 %"] placeAnnotations(): Placing annotation 12: physical object Ancillary data = ["physical object", "P: 201.1 db", "T: 9.096 C", "S: 34.006", "O: 1.532 ml/l", "L: 88.43 %"] placeAnnotations(): Placing annotation 13: physical object Ancillary data = ["physical object", "P: 201.1 db", "T: 9.105 C", "S: 34.004", "O: 1.535 ml/l", "L: 89.1 %"] placeAnnotations(): Placing annotation 14: physical object Ancillary data = ["physical object", "P: 201.1 db", "T: 9.105 C", "S: 34.004", "O: 1.535 ml/l", "L: 89.1 %"] placeAnnotations(): Placing annotation 15: physical object Ancillary data = ["physical object", "P: 201.2 db", "T: 9.106 C", "S: 34.004", "O: 1.537 ml/l", "L: 89.65 %"] placeAnnotations(): Placing annotation 16: Ancillary data = ["", "P: 201.1 db", "T: 9.114 C", "S: 33.999", "O: 1.544 ml/l", "L: 88.02 %"] inline_name = vnta3737anno01.wrl frameGrabs(): Getting images from http://search.mbari.org/ARCHIVE/frameGrabs/Ventana/stills/2013/vnta3737... frameGrabs(): matched 05_27_10_12 in