2012-03-16 13:28:23,439-0600 DEBUG Loader arguments: [-config, /glade/home/mickelso/omwg/rundir//cf.properties, -sites.file, /glade/home/mickelso/omwg/rundir//sites.xml, -tc.file, /glade/home/mickelso/omwg/rundir//tc.data, -cdm.file, /glade/home/mickelso/omwg/rundir//fs.data, /glade/home/mickelso/omwg/rundir//popdiag.swift, -wrkdir=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/, -msroot=/home/mickelso/cesm/, -sobsfilefn=PHC2_SALT_gx1v6_ann_avg.nc, -tobsfilefn=PHC2_TEMP_gx1v6_ann_avg.nc, -case=c40.t62x1.verif.03, -resolution=gx1v6, -year0=1, -year1=10, -ncl_wrap=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/ncl_wrap.pro, -cntrlvls=std, -cpl=7, -fluxobsfile=a.b27.03.mean.1984-2006.nc, -idlcolortable=42, -idlcolortablefile=/glade/home/mickelso/omwg/popdiag//idl_lib/colortables/colors1.tbl, -idllndcolor=255, -idlmaxcolor=254, -idlmincolor=1, -rhoobsfile=PHC2_RHO0_gx1v6.nc, -sobsfilefp=PHC2_SALT_gx1v6_ann_avg.nc, -sshobsfile=1992-2002MDOT060401.gx1v6.nc, -sstobsfile=roisst.nc, -tavgfile=tavg.1.10.nc, -tobsfilefp=PHC2_TEMP_gx1v6_ann_avg.nc, -togataofile=meanfit_m.nc, -windobsfile=gx1v3.022.clim.2000-2004.nc, -tobsdir=/cgd/oce/bates/OBS/phc/gx1v6, -need_clim=0, -needtavg=1, -ncarg_root=/contrib/ncl-6.1.0/, -nclpath=/glade/home/mickelso/omwg/popdiag//ncl_lib, -diagrootpath=/glade/home/mickelso/omwg/popdiag/, -ncarg_colormap_path=/glade/home/mickelso/omwg/popdiag//ncl_lib/colormaps:/contrib/ncl-6.1.0//lib/ncarg/colormaps, -seasavgtemp=c40.t62x1.verif.03.pop.h.TEMP.mavg_0001-0010.nc, -seasavgsalt=c40.t62x1.verif.03.pop.h.SALT.mavg_0001-0010.nc, -seasavgfile=c40.t62x1.verif.03.pop.h.TEMP.mavg_0001-0010.nc, -seasavgrho=c40.t62x1.verif.03.pop.h.RHO.mavg_0001-0010.nc, -compute_rho=0, -doplots=1, -need_clim_TEMP=0, -need_clim_SALT=0] 2012-03-16 13:28:23,502-0600 DEBUG Loader Max heap: 238616576 2012-03-16 13:28:23,727-0600 DEBUG textfiles BEGIN CDM FILE: # Describe the CDM for my job #rule .*.nc DIRECT /glade/scratch/mickelso/omwg-swift/swiftSandbox rule .*.nc DEFAULT rule .* DEFAULT 2012-03-16 13:28:23,727-0600 DEBUG textfiles END CDM FILE: 2012-03-16 13:28:24,541-0600 DEBUG textfiles BEGIN SWIFTSCRIPT: type file; string yes = "1"; string no = "0"; int need_clim = @toint(@arg("need_clim",yes));trace(@strcat("need_clim: ",@arg("need_clim"))); int need_clim_TEMP = @toint(@arg("need_clim_TEMP",yes));trace(@strcat("need_clim_TEMP: ",@arg("need_clim_TEMP"))); int need_clim_SALT = @toint(@arg("need_clim_SALT",yes));trace(@strcat("need_clim_SALT: ",@arg("need_clim_SALT"))); int needtavg = @toint(@arg("needtavg",yes));trace(@strcat("needtavg: ",@arg("needtavg"))); int doplots = @toint(@arg("doplots",yes));trace(@strcat("doplots: ",@arg("doplots"))); # Set data info string wrkdir = @arg("wrkdir");trace(@strcat("wrkdir: ",@arg("wrkdir"))); string msroot = @arg("msroot");trace(@strcat("msroot: ",@arg("msroot"))); string caseN = @arg("case");trace(@strcat("case: ",@arg("case"))); string resolution = @arg("resolution");trace(@strcat("resolution: ",@arg("resolution"))); int year0 = @toint(@arg("year0"));trace(@strcat("test_begin: ",@arg("year0"))); int year1 = @toint(@arg("year1"));trace(@strcat("test_end: ",@arg("year1"))); #int test_nyrs = @toint(@arg("test_nyrs"));trace(@strcat("test_nyrs: ",@arg("test_nyrs"))); int test_nyrs = ((year1 - year0) +1); int years_to_test[] = [year0 : year0 + test_nyrs - 1 : 1]; string sobsfilefn = @arg("sobsfilefn"); string tobsfilefn = @arg("tobsfilefn"); string seasavgtemp = @arg("seasavgtemp"); string seasavgsalt = @arg("seasavgsalt"); int compute_rho = @toint(@arg("compute_rho")); #ncl file list string ncl_list = @arg("ncl_wrap");trace(@strcat("ncl_wrap: ",@arg("ncl_wrap"))); #Variables only used by NCL calls string cntrlvls = @arg("cntrlvls"); string cpl = @arg("cpl"); string fluxobsfile = @arg("fluxobsfile"); string idlcolortable = @arg("idlcolortable"); string idlcolortablefile = @arg("idlcolortablefile"); string idllndcolor = @arg("idllndcolor"); string idlmaxcolor = @arg("idlmaxcolor"); string idlmincolor = @arg("idlmincolor"); string rhoobsfile = @arg("rhoobsfile"); string sobsfilefp = @arg("sobsfilefp"); string sshobsfile = @arg("sshobsfile"); string sstobsfile = @arg("sstobsfile"); string tavgfile = @arg("tavgfile"); string tobsfilefp = @arg("tobsfilefp"); string togataofile = @arg("togataofile"); string windobsfile = @arg("windobsfile"); string tobsdir = @arg("tobsdir"); string ncarg_root =@arg("ncarg_root"); string nclpath = @arg("nclpath"); string diagrootpath = @arg("diagrootpath"); string ncarg_colormap_path = @arg("ncarg_colormap_path"); string seasavgfile = @arg("seasavgfile"); string seasavgrho = @arg("seasavgrho"); #================================================================================ # App Calls #================================================================================ (string yrstr)yearprint(int yr){ if(yr<10) { yrstr = @strcat("000",yr); } if(yr >= 10 && yr <100) { yrstr=@strcat("00",yr); } if(yr >= 100 && yr <1000) { yrstr=@strcat("0",yr); } if(yr >= 1000) { yrstr=@strcat(yr); } } (string mstr)monthprint(int m){ if(m<10) { mstr = @strcat("0",m); } else { mstr = @strcat(m); } } # Calls ncra (file out) Record_Average(string f[], file hsiFile[]){ app{ ncra "-O" f @filename(out);} } # Calls ncra (file out) Record_Average_Files(file f[], string firstFile, string pattern){ app{ ncra "-O" "-n" pattern firstFile @filename(out);} } # Calls ncra (file out) Record_Average_var(string vars, string f[]){ app{ ncra "-O" "-v" vars f @filename(out);} } # Calls ncea (file out) Ensemble_Average_var(string vars, string f[], file monhsiFileL[]){ app{ ncea "-O" "-v" vars f @filename(out);} } # Calls ncrcat (file out) Record_Cat(file f[]){ app{ ncrcat "-O" @filenames(f) @filename(out);} } # Calls ncks (file out) ncks_var(string var, file f){ app{ ncks "-O" "-v" var @filename(f) @filename(out);} } # Calls ncks (file out) ncks_varS(string var, string f){ app{ ncks "-O" "-v" var f @filename(out);} } # Calls za (file out) zonal_average(file f){ app{ za "-O" "-time_const" "-o" @filename(out) @filename(f);} } (file out)Append_ncks_za(file file1, string file2fp, string obsdir){ app {Append_ncks_za_csh @filename(file1) file2fp @filename(out) obsdir;} } (file out)Check_Mon_hsi(string msrootL, string casenameL, int year0L, int year1L, string workdir, string toollib, int needtavgL, int needclim) { app{check_hsi_mfiles_csh msrootL casenameL year0L year1L @filename(out) workdir toollib needtavgL needclim;} } (file out)GethsiFile(string fileName, string wrkdirL, string msrootL, string caseNL, string typ){ app {get_hsi_csh fileName wrkdirL @filename(out) msrootL caseNL typ;} } (file out)Check_yavg_hsi(string msrootL, string caseNL, int year0L, int year1L, string wrkdirL){ app {Check_yavg_hsi_csh msrootL caseNL year0L year1L wrkdirL @filename(out);} } (file out)Check_yavg(file complete[], string caseNL, string wrkdirL, int yr0, int yr1){ app {Check_yavg_csh caseNL wrkdirL yr0 yr1 @filename(out);} } (file out)store_file(file fp, string msrootL, string caseNL, string dir, string f){ app {store_file_csh @filename(fp) msrootL caseNL dir @filename(out) f;} } (file out)removeMF(string f, file tavgFile, file mavgFile){ app {removeMF_csh f @filename(out) ;} } (file out)ncl_wrapper(file tavg_c, file mavg_c, file za_c, string f, string arg1,string arg2,string arg3,string arg4,string arg5,string arg6,string arg7, string arg8,string arg9,string arg10,string arg11,string arg12,string arg13,string arg14, string arg15,string arg16,string arg17,string arg18, int arg19,int arg20,string wrkdirL,string ncarg_rootL,string nclpathL,string ncarg_colormap_pathL, string arg21, string arg22, string arg23, string arg24, string arg25){ app {ncl_wrapper_csh f @filename(out) arg1 arg2 arg3 arg4 arg5 arg6 arg7 arg8 arg9 arg10 arg11 arg12 arg13 arg14 arg15 arg16 arg17 arg18 arg19 arg20 wrkdirL ncarg_rootL nclpathL ncarg_colormap_pathL arg21 arg22 arg23 arg2 arg25;} } (file out)run_ncl(string nclFile, file f1, file f2, string arg1,string arg2,string arg3,string arg4,string arg5,string arg6,string arg7, string arg8,string arg9,string arg10,string arg11,string arg12,string arg13,string arg14, string arg15,string arg16,string arg17,string arg18, int arg19,int arg20,string wrkdirL,string ncarg_rootL,string nclpathL,string ncarg_colormap_pathL, string arg21, string arg22, string arg23, string arg24, string arg25){ app {ncl_wrapper_csh nclFile @filename(out) arg1 arg2 arg3 arg4 arg5 arg6 arg7 arg8 arg9 arg10 arg11 arg12 arg13 arg14 arg15 arg16 arg17 arg18 arg19 arg20 wrkdirL ncarg_rootL nclpathL ncarg_colormap_pathL arg21 arg22 arg23 arg24 arg25;} } (file out)getFileList(file nclF[],string wrkdirL) { app {get_PSList_csh wrkdirL @filename(out);} } (file out)convertImages(string wrkdirL, string fn, string diagrootpathL){ app{convert_images_csh wrkdirL fn @filename(out) diagrootpathL;} } (file out)create_blank_file(){ app{dummy_file_csh @filename(out);} } (file out)create_blank_file_f(file f){ app{dummy_file_csh @filename(out);} } (file out)create_blank_file_f3(file f1, file f2, file f3){ app{dummy_file_csh @filename(out);} } #================================================================================ # Procedures #================================================================================ (file outfile) CalcTAVG(string dpath, string casename, string msr, int yr, file monhsiFileL[]) { int m[]=[1,2,3,4,5,6,7,8,9,10,11,12]; string filesInYear[]; foreach month in m{ filesInYear[month]=@strcat(dpath,casename,".pop.h.",yearprint(yr),"-",monthprint(month),".nc"); } outfile = Record_Average(filesInYear,monhsiFileL); } (file mavgFile) CalcMAVG(string dpath, string casename, string msr, int yr0, int yr1, int years[], file monhsiFileL[]) { string monthNames[]=["jan","feb","mar","apr","may","jun","jul","aug","sep","oct","nov","dec"]; file monthFiles[]; string var1 = "TEMP"; string var2 = "SALT"; int m[]=[1,2,3,4,5,6,7,8,9,10,11,12]; foreach month in m { string mname[]; foreach year in years { mname[year] = @strcat(dpath,casename,".pop.h.",yearprint(year),"-",monthprint(month),".nc"); } file monthFile; monthFile = Ensemble_Average_var("TEMP,SALT", mname, monhsiFileL); monthFiles[month] = monthFile; } mavgFile = Record_Cat(monthFiles); } #================================================================================ # Get hsi files #================================================================================ (file hsiFileL[]) hsi_files(string HSIfilesL[],string wrkdirL,string msrootL,string caseNL,string typ) { foreach f,ix in HSIfilesL{ if (f != " "){ hsiFileL[ix]=GethsiFile(f,wrkdirL,msrootL,caseNL,typ); } } } #================================================================================ # HSI #================================================================================ # Check to see if we need to grab files from hsi file monHSIFileList; monHSIFileList = Check_Mon_hsi(msroot,caseN,year0,year1,wrkdir,@strcat(diagrootpath,"/tool_lib"),needtavg,need_clim); string monHSIfiles[] = readData(monHSIFileList); file monhsiFileComplete[]; if ((needtavg == 1 && yavgToCompute[0] != "ALL FOUND") || need_clim == 1){ monhsiFileComplete = hsi_files(monHSIfiles,wrkdir,msroot,caseN,"hist"); } else { monhsiFileComplete[0] = create_blank_file(); } string datapath; if (monHSIfiles[1] == " "){ datapath = msroot; } else { datapath = wrkdir; } #================================================================================ # MAVG #================================================================================ #Compute mavg files file mavgFile ; file mavgTFile ; file mavgSFile ; file mavgRFile; file mavgFileD; file mavgTFileD; file mavgSFileD; file mavgT_complete; file mavgS_complete; if (need_clim == 1){ mavgFile = CalcMAVG(datapath, caseN, wrkdir, year0, year1, years_to_test,monhsiFileComplete); if (need_clim_TEMP == 1) { mavgTFile = ncks_var("TEMP",mavgFile); file save_T_complete = store_file(mavgTFile,msroot,caseN,"/ocn/proc/tavg/monthly/",seasavgtemp); } else { mavgTFileD = create_blank_file(); } if (need_clim_SALT == 1) { mavgSFile = ncks_var("SALT",mavgFile); file save_S_complete = store_file(mavgSFile,msroot,caseN,"/ocn/proc/tavg/monthly/",seasavgsalt); } else { mavgSFileD = create_blank_file(); } if (need_clim_TEMP == 1){ mavgT_complete = create_blank_file_f(mavgTFile); } else { mavgT_complete = create_blank_file_f(mavgTFileD); } if (need_clim_SALT == 1){ mavgS_complete = create_blank_file_f(mavgSFile); } else { mavgS_complete = create_blank_file_f(mavgSFileD); } if (compute_rho == 1){ mavgRFile = run_ncl(@strcat(nclpath,"/compute_rho.ncl"),mavgT_complete,mavgS_complete, caseN,cntrlvls,cpl,fluxobsfile,idlcolortable,idlcolortablefile,idllndcolor,idlmaxcolor,idlmincolor,resolution, rhoobsfile,sobsfilefp,sshobsfile,sstobsfile,tavgfile,tobsfilefp,togataofile,windobsfile,year0,year1,wrkdir, ncarg_root,nclpath,ncarg_colormap_path,seasavgfile,seasavgrho,seasavgtemp,seasavgsalt,tavgfile); } else { mavgRFile = create_blank_file(); } } else { mavgFileD = create_blank_file(); mavgTFileD = create_blank_file(); mavgSFileD = create_blank_file(); if (compute_rho == 1){ mavgRFile = run_ncl(@strcat(nclpath,"/compute_rho.ncl"),mavgTFileD,mavgSFileD, caseN,cntrlvls,cpl,fluxobsfile,idlcolortable,idlcolortablefile,idllndcolor,idlmaxcolor,idlmincolor,resolution, rhoobsfile,sobsfilefp,sshobsfile,sstobsfile,tavgfile,tobsfilefp,togataofile,windobsfile,year0,year1,wrkdir, ncarg_root,nclpath,ncarg_colormap_path,seasavgfile,seasavgrho,seasavgtemp,seasavgsalt,tavgfile); } else { mavgRFile = create_blank_file(); } } file MAVG_complete; if (need_clim == 1){ MAVG_complete = create_blank_file_f3(mavgT_complete,mavgS_complete,mavgRFile); } else { MAVG_complete = create_blank_file_f3(mavgTFileD,mavgSFileD,mavgRFile); } #================================================================================ # TAVG #================================================================================ #### Check if any of the yearly avg files are located on hsi file yavg_hsi_list; yavg_hsi_list = Check_yavg_hsi(msroot,caseN,year0,year1,wrkdir); string yavgHSIfiles[] = readData(yavg_hsi_list); file yavghsiComplete[]; if (needtavg == 1){ yavghsiComplete = hsi_files(yavgHSIfiles,wrkdir,msroot,caseN,"year"); } else { yavghsiComplete[0] = create_blank_file(); } ### Create a list of yearly averages that need to be computed file yavg_list; yavg_list = Check_yavg(yavghsiComplete,caseN,wrkdir,year0,year1); string yavgToCompute[] = readData(yavg_list); ### Compute the yearly averages file outfiles[]; file outfileTA ; file outfileTAD; if (needtavg == 1) { if (yavgToCompute[0] != "ALL FOUND"){ foreach yr in yavgToCompute { int year = @toint(yr); file outfile; outfile = CalcTAVG(datapath, caseN, wrkdir, year,monhsiFileComplete); outfiles[year] = outfile; string fn = @strcat(caseN,".pop.h.",yearprint(year),".nc"); file save_ann_complete = store_file(outfile,msroot,caseN,"/ocn/proc/tavg/annual/",fn); } } else { outfiles[0] = create_blank_file(); } } ### Compute tavg file if (needtavg == 1) { outfileTA = Record_Average_Files(outfiles,@strcat(wrkdir,caseN,".pop.h.",yearprint(year0),".nc"),@strcat(test_nyrs,",4,1")); } else { outfileTAD = create_blank_file(); } file TAVG_complete; if (needtavg == 1) { TAVG_complete = create_blank_file_f(outfileTA); } else { TAVG_complete = create_blank_file_f(outfileTAD); } #================================================================================ # ZA #================================================================================ file zataFile ; file tobsFileTemp1; file za_tobs ; file sobsFileTemp1; file za_sobs ; file zataFileD; file tobsFileTemp1D; file za_tobsD; file sobsFileTemp1D; file za_sobsD; if (needtavg == 1) { zataFile = zonal_average(outfileTA); tobsFileTemp1 = Append_ncks_za(outfileTA,tobsfilefp,tobsdir); za_tobs = zonal_average(tobsFileTemp1); sobsFileTemp1 = Append_ncks_za(outfileTA,sobsfilefp,tobsdir); za_sobs = zonal_average(sobsFileTemp1); } else { zataFileD = create_blank_file(); za_tobsD = create_blank_file(); za_sobsD = create_blank_file(); } file ZA_complete; if (needtavg == 1) { ZA_complete = create_blank_file_f3(zataFile,za_tobs,za_sobs); } else { ZA_complete = create_blank_file_f3(zataFileD,za_tobsD,za_sobsD); } #================================================================================ # Remove monthly files #================================================================================ foreach f in monHSIfiles{ file rmComplete; rmComplete = removeMF(@strcat(wrkdir,f),TAVG_complete,MAVG_complete); } #================================================================================ # NCL FILE LIST #================================================================================ string ncl_files[] = readData(ncl_list); file ncl_finished[]; if (doplots == 1) { foreach f,i in ncl_files{ ncl_finished[i] = ncl_wrapper(TAVG_complete, MAVG_complete, ZA_complete, f,caseN,cntrlvls,cpl,fluxobsfile,idlcolortable,idlcolortablefile,idllndcolor,idlmaxcolor,idlmincolor,resolution, rhoobsfile,sobsfilefp,sshobsfile,sstobsfile,tavgfile,tobsfilefp,togataofile,windobsfile,year0,year1,wrkdir, ncarg_root,nclpath,ncarg_colormap_path,seasavgfile,seasavgrho,seasavgtemp,seasavgsalt,tavgfile); } } #================================================================================ # Convert the image files #================================================================================ file psFileList; if (doplots == 1){ psFileList = getFileList(ncl_finished,wrkdir); string psFiles[] = readData(psFileList); foreach f in psFiles{ file convertFile; convertFile = convertImages(wrkdir,f,diagrootpath); } } 2012-03-16 13:28:24,542-0600 DEBUG textfiles END SWIFTSCRIPT: 2012-03-16 13:28:24,566-0600 INFO Loader /glade/home/mickelso/omwg/rundir//popdiag.swift: source file is new. Recompiling. 2012-03-16 13:28:26,638-0600 INFO Karajan Validation of XML intermediate file was successful 2012-03-16 13:28:26,853-0600 WARN VariableScope Variable mavgFile defined in scope 1305701886 shadows variable of same name in scope 2104764160 2012-03-16 13:28:27,606-0600 INFO Karajan Unused variable tobsFileTemp1D 2012-03-16 13:28:27,606-0600 INFO Karajan Unused variable sobsFileTemp1D 2012-03-16 13:28:28,208-0600 DEBUG Loader setting: sites.file to: /glade/home/mickelso/omwg/rundir//sites.xml 2012-03-16 13:28:28,208-0600 DEBUG Loader setting: tc.file to: /glade/home/mickelso/omwg/rundir//tc.data 2012-03-16 13:28:28,208-0600 DEBUG textfiles using default sites file 2012-03-16 13:28:28,344-0600 INFO VDL2ExecutionContext Stack dump: Level 1 [iA = 0, iB = 0, bA = false, bB = false] vdl:instanceconfigfile = /glade/home/mickelso/omwg/rundir//cf.properties vdl:instanceconfig = Swift configuration [/glade/home/mickelso/omwg/rundir//cf.properties] vdl:operation = run PATH_SEPARATOR = / swift.home = /glade/home/mickelso/swift-0.93RC4/bin/.. 2012-03-16 13:28:30,983-0600 INFO unknown Using sites file: /glade/home/mickelso/omwg/rundir//sites.xml 2012-03-16 13:28:31,094-0600 INFO unknown Using tc.data: /glade/home/mickelso/omwg/rundir//tc.data 2012-03-16 13:28:31,232-0600 INFO AbstractScheduler Setting resources to: {localhost=localhost} 2012-03-16 13:28:32,423-0600 INFO unknown Swift svn swift-r5277 cog-r3320 2012-03-16 13:28:32,436-0600 INFO unknown RUNID id=run:20120316-1328-l17ob5i5 2012-03-16 13:28:34,827-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-3 name=trace 2012-03-16 13:28:34,828-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-5 name=trace 2012-03-16 13:28:34,829-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-7 name=trace 2012-03-16 13:28:34,829-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-9 name=trace 2012-03-16 13:28:34,830-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-11 name=trace 2012-03-16 13:28:34,830-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-13 name=trace 2012-03-16 13:28:34,831-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-15 name=trace 2012-03-16 13:28:34,832-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-19 name=trace 2012-03-16 13:28:34,833-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-32 name=trace 2012-03-16 13:28:34,831-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-17 name=trace 2012-03-16 13:28:34,832-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-23 name=trace 2012-03-16 13:28:34,832-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-21 name=trace 2012-03-16 13:28:34,840-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-66 name=readData 2012-03-16 13:28:34,839-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-57 name=readData 2012-03-16 13:28:34,840-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-69 name=readData 2012-03-16 13:28:34,841-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-80 name=readData 2012-03-16 13:28:34,858-0600 INFO SetFieldValue Set: yes=1 2012-03-16 13:28:34,861-0600 INFO SetFieldValue Set: no=0 2012-03-16 13:28:34,864-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,868-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,869-0600 INFO SetFieldValue Set: cpl=7 2012-03-16 13:28:34,869-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,865-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,865-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,872-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,865-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,865-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,874-0600 INFO SetFieldValue Set: tobsdir=/cgd/oce/bates/OBS/phc/gx1v6 2012-03-16 13:28:34,874-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,874-0600 INFO SetFieldValue Set: sstobsfile=roisst.nc 2012-03-16 13:28:34,875-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,875-0600 INFO SetFieldValue Set: sobsfilefp=PHC2_SALT_gx1v6_ann_avg.nc 2012-03-16 13:28:34,876-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,876-0600 INFO SetFieldValue Set: tavgfile=tavg.1.10.nc 2012-03-16 13:28:34,870-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,876-0600 INFO SetFieldValue Set: nclpath=/glade/home/mickelso/omwg/popdiag//ncl_lib 2012-03-16 13:28:34,877-0600 INFO SetFieldValue Set: tobsfilefp=PHC2_TEMP_gx1v6_ann_avg.nc 2012-03-16 13:28:34,877-0600 INFO SetFieldValue Set: idllndcolor=255 2012-03-16 13:28:34,865-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,877-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,876-0600 INFO SetFieldValue Set: fluxobsfile=a.b27.03.mean.1984-2006.nc 2012-03-16 13:28:34,878-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,875-0600 INFO SetFieldValue Set: rhoobsfile=PHC2_RHO0_gx1v6.nc 2012-03-16 13:28:34,878-0600 INFO SetFieldValue Set: sobsfilefn=PHC2_SALT_gx1v6_ann_avg.nc 2012-03-16 13:28:34,878-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,875-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,879-0600 INFO SetFieldValue Set: cntrlvls=std 2012-03-16 13:28:34,875-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,874-0600 INFO SetFieldValue Set: msroot=/home/mickelso/cesm/ 2012-03-16 13:28:34,867-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,880-0600 INFO SetFieldValue Set: idlcolortable=42 2012-03-16 13:28:34,867-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,880-0600 INFO SetFieldValue Set: seasavgtemp=c40.t62x1.verif.03.pop.h.TEMP.mavg_0001-0010.nc 2012-03-16 13:28:34,867-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,885-0600 INFO SetFieldValue Set: caseN=c40.t62x1.verif.03 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,886-0600 INFO SetFieldValue Set: diagrootpath=/glade/home/mickelso/omwg/popdiag/ 2012-03-16 13:28:34,872-0600 INFO SetFieldValue Set: seasavgsalt=c40.t62x1.verif.03.pop.h.SALT.mavg_0001-0010.nc 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,888-0600 INFO SetFieldValue Set: togataofile=meanfit_m.nc 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,871-0600 INFO SetFieldValue Set: resolution=gx1v6 2012-03-16 13:28:34,890-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,892-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,866-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,865-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,892-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,893-0600 INFO SetFieldValue Set: need_clim=0.0 2012-03-16 13:28:34,892-0600 INFO SetFieldValue Set: idlmaxcolor=254 2012-03-16 13:28:34,892-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,892-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,894-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,892-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,892-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,891-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,891-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,891-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,890-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,890-0600 INFO SetFieldValue Set: ncl_list=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/ncl_wrap.pro 2012-03-16 13:28:34,887-0600 INFO SetFieldValue Set: tobsfilefn=PHC2_TEMP_gx1v6_ann_avg.nc 2012-03-16 13:28:34,886-0600 INFO SetFieldValue Set: wrkdir=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/ 2012-03-16 13:28:34,898-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,898-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,899-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,885-0600 INFO SetFieldValue Set: windobsfile=gx1v3.022.clim.2000-2004.nc 2012-03-16 13:28:34,880-0600 INFO SetFieldValue Set: ncarg_root=/contrib/ncl-6.1.0/ 2012-03-16 13:28:34,879-0600 INFO SetFieldValue Set: seasavgrho=c40.t62x1.verif.03.pop.h.RHO.mavg_0001-0010.nc 2012-03-16 13:28:34,902-0600 INFO SetFieldValue Set: swift#mapper#17098=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/za_PHC2_TEMP_gx1v6_ann_avg.nc 2012-03-16 13:28:34,879-0600 INFO SetFieldValue Set: seasavgfile=c40.t62x1.verif.03.pop.h.TEMP.mavg_0001-0010.nc 2012-03-16 13:28:34,879-0600 INFO SetFieldValue Set: ncarg_colormap_path=/glade/home/mickelso/omwg/popdiag//ncl_lib/colormaps:/contrib/ncl-6.1.0//lib/ncarg/colormaps 2012-03-16 13:28:34,879-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,878-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,907-0600 INFO SetFieldValue Set: year0=1.0 2012-03-16 13:28:34,878-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,908-0600 INFO SetFieldValue Set: year1=10.0 2012-03-16 13:28:34,908-0600 DEBUG vdl:mains SCOPE thread=0-63 2012-03-16 13:28:34,908-0600 DEBUG vdl:mains SCOPE thread=0-64 2012-03-16 13:28:34,878-0600 INFO SetFieldValue Set: idlmincolor=1 2012-03-16 13:28:34,908-0600 INFO SetFieldValue Set: swift#mapper#17096=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/za_tavg.1.10.nc 2012-03-16 13:28:34,910-0600 INFO SetFieldValue Set: swift#mapper#17083=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/mavg.1.10.nc 2012-03-16 13:28:34,906-0600 INFO SetFieldValue Set: compute_rho=0.0 2012-03-16 13:28:34,901-0600 INFO SetFieldValue Set: swift#mapper#17100=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/za_PHC2_SALT_gx1v6_ann_avg.nc 2012-03-16 13:28:34,900-0600 INFO SetFieldValue Set: swift#mapper#17087=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/c40.t62x1.verif.03.pop.h.SALT.mavg_0001-0010.nc 2012-03-16 13:28:34,900-0600 INFO SetFieldValue Set: swift#mapper#17086=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/c40.t62x1.verif.03.pop.h.TEMP.mavg_0001-0010.nc 2012-03-16 13:28:34,899-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,897-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,896-0600 INFO SetFieldValue Set: need_clim_TEMP=0.0 2012-03-16 13:28:34,912-0600 INFO SetFieldValue Set: swift#mapper#17091=/glade/scratch/mickelso/omwg-swift/swiftSandbox2/tavg.1.10.nc 2012-03-16 13:28:34,894-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,894-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,913-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,913-0600 INFO SetFieldValue Set: needtavg=1.0 2012-03-16 13:28:34,893-0600 INFO VDLFunction FUNCTION: arg() 2012-03-16 13:28:34,893-0600 INFO SetFieldValue Set: sshobsfile=1992-2002MDOT060401.gx1v6.nc 2012-03-16 13:28:34,893-0600 INFO SetFieldValue Set: idlcolortablefile=/glade/home/mickelso/omwg/popdiag//idl_lib/colortables/colors1.tbl 2012-03-16 13:28:34,913-0600 INFO SetFieldValue Set: doplots=1.0 2012-03-16 13:28:34,912-0600 INFO VDLFunction FUNCTION: toint() 2012-03-16 13:28:34,920-0600 INFO SetFieldValue Set: need_clim_SALT=0.0 2012-03-16 13:28:34,921-0600 INFO SetFieldValue Set: test_nyrs=10.0 2012-03-16 13:28:34,922-0600 DEBUG vdl:mains FOREACH_IT_START line=458 thread=0-79-0 2012-03-16 13:28:34,923-0600 DEBUG vdl:mains SCOPE thread=0-78 2012-03-16 13:28:34,923-0600 DEBUG vdl:mains SCOPE thread=0-72 2012-03-16 13:28:34,924-0600 DEBUG vdl:mains SCOPE thread=0-73 2012-03-16 13:28:34,924-0600 DEBUG vdl:mains SCOPE thread=0-67 2012-03-16 13:28:34,926-0600 DEBUG vdl:mains SCOPE thread=0-81 2012-03-16 13:28:34,930-0600 WARN trace SwiftScript trace: need_clim_SALT: 0 2012-03-16 13:28:34,931-0600 DEBUG vdl:mains SCOPE thread=0-82 2012-03-16 13:28:34,931-0600 WARN trace SwiftScript trace: ncl_wrap: /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ncl_wrap.pro 2012-03-16 13:28:34,932-0600 DEBUG vdl:mains SCOPE thread=0-71 2012-03-16 13:28:34,931-0600 DEBUG vdl:mains SCOPE thread=0-77 2012-03-16 13:28:34,935-0600 WARN trace SwiftScript trace: needtavg: 1 2012-03-16 13:28:34,952-0600 WARN trace SwiftScript trace: need_clim_TEMP: 0 2012-03-16 13:28:34,935-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-32 2012-03-16 13:28:34,932-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-7 2012-03-16 13:28:34,932-0600 WARN trace SwiftScript trace: test_begin: 1 2012-03-16 13:28:34,958-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-21 2012-03-16 13:28:34,957-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-5 2012-03-16 13:28:34,961-0600 INFO SetFieldValue Set: years_to_test={} 2012-03-16 13:28:34,956-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-9 2012-03-16 13:28:34,968-0600 DEBUG create_blank_file_f PROCEDURE line=210 thread=0-73-1 name=create_blank_file_f 2012-03-16 13:28:34,968-0600 DEBUG zonal_average PROCEDURE line=128 thread=0-77-0 name=zonal_average 2012-03-16 13:28:34,972-0600 DEBUG check_yavg_hsi PROCEDURE line=149 thread=0-65 name=Check_yavg_hsi 2012-03-16 13:28:34,973-0600 DEBUG create_blank_file_f3 PROCEDURE line=215 thread=0-78-1 name=create_blank_file_f3 2012-03-16 13:28:34,973-0600 DEBUG check_yavg PROCEDURE line=154 thread=0-68 name=Check_yavg 2012-03-16 13:28:34,974-0600 DEBUG append_ncks_za PROCEDURE line=133 thread=0-77-1 name=Append_ncks_za 2012-03-16 13:28:34,946-0600 DEBUG vdl:mains SCOPE thread=0-79-0 2012-03-16 13:28:34,944-0600 WARN trace SwiftScript trace: wrkdir: /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ 2012-03-16 13:28:34,975-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-13 2012-03-16 13:28:34,944-0600 WARN trace SwiftScript trace: msroot: /home/mickelso/cesm/ 2012-03-16 13:28:34,944-0600 WARN trace SwiftScript trace: test_end: 10 2012-03-16 13:28:34,986-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-23 2012-03-16 13:28:34,943-0600 DEBUG vdl:mains SCOPE thread=0-63-3 2012-03-16 13:28:34,979-0600 DEBUG create_blank_file PROCEDURE line=205 thread=0-63-2 name=create_blank_file 2012-03-16 13:28:34,941-0600 DEBUG create_blank_file PROCEDURE line=205 thread=0-63-0 name=create_blank_file 2012-03-16 13:28:34,934-0600 WARN trace SwiftScript trace: need_clim: 0 2012-03-16 13:28:34,939-0600 WARN trace SwiftScript trace: resolution: gx1v6 2012-03-16 13:28:34,938-0600 WARN trace SwiftScript trace: doplots: 1 2012-03-16 13:28:34,938-0600 WARN trace SwiftScript trace: case: c40.t62x1.verif.03 2012-03-16 13:28:34,989-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-11 2012-03-16 13:28:34,989-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-19 2012-03-16 13:28:34,988-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-3 2012-03-16 13:28:34,985-0600 DEBUG vdl:mains INTERNALPROC_START thread=0-82-1 name=readData 2012-03-16 13:28:34,990-0600 DEBUG create_blank_file PROCEDURE line=205 thread=0-63-3-1 name=create_blank_file 2012-03-16 13:28:34,980-0600 DEBUG create_blank_file PROCEDURE line=205 thread=0-63-1 name=create_blank_file 2012-03-16 13:28:34,983-0600 INFO hsi_files STARTCOMPOUND thread=0-67-1 name=hsi_files 2012-03-16 13:28:34,982-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-15 2012-03-16 13:28:34,989-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-17 2012-03-16 13:28:34,989-0600 DEBUG check_mon_hsi PROCEDURE line=138 thread=0-56 name=Check_Mon_hsi 2012-03-16 13:28:35,000-0600 INFO yearprint STARTCOMPOUND thread=0-72-1-2 name=yearprint 2012-03-16 13:28:35,002-0600 DEBUG getfilelist PROCEDURE line=195 thread=0-82-0 name=getFileList 2012-03-16 13:28:35,005-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-80 2012-03-16 13:28:35,009-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-0 2012-03-16 13:28:35,013-0600 DEBUG vdl:mains SCOPE thread=0-81-1-0 2012-03-16 13:28:35,009-0600 DEBUG yearprint SCOPE thread=0-72-1-2-0 2012-03-16 13:28:35,018-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-4 2012-03-16 13:28:35,018-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-10 2012-03-16 13:28:35,018-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-12 2012-03-16 13:28:35,018-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-13 2012-03-16 13:28:35,019-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-14 2012-03-16 13:28:35,019-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-11 2012-03-16 13:28:35,020-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-23 2012-03-16 13:28:35,020-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-24 2012-03-16 13:28:35,020-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-25 2012-03-16 13:28:35,028-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-20 2012-03-16 13:28:35,028-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-19 2012-03-16 13:28:35,028-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-21 2012-03-16 13:28:35,029-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-22 2012-03-16 13:28:35,029-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-3 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-26 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-27 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-28 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-30 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-31 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-29 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-33 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-34 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-35 2012-03-16 13:28:35,030-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-36 2012-03-16 13:28:35,031-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-37 2012-03-16 13:28:35,031-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-6 2012-03-16 13:28:35,031-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-8 2012-03-16 13:28:35,031-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-17 2012-03-16 13:28:35,031-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-18 2012-03-16 13:28:35,032-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-32 2012-03-16 13:28:35,033-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-2 2012-03-16 13:28:35,033-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-1 2012-03-16 13:28:35,033-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-9 2012-03-16 13:28:35,033-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-16 2012-03-16 13:28:35,033-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-15 2012-03-16 13:28:35,033-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-7 2012-03-16 13:28:35,035-0600 DEBUG vdl:mains SCOPE thread=0-81-1-30 2012-03-16 13:28:35,033-0600 DEBUG vdl:mains FOREACH_IT_START line=470 thread=0-81-1-5 2012-03-16 13:28:35,035-0600 DEBUG vdl:mains SCOPE thread=0-81-1-33 2012-03-16 13:28:35,037-0600 DEBUG vdl:mains SCOPE thread=0-81-1-35 2012-03-16 13:28:35,037-0600 DEBUG vdl:mains SCOPE thread=0-81-1-17 2012-03-16 13:28:35,037-0600 DEBUG vdl:mains SCOPE thread=0-81-1-18 2012-03-16 13:28:35,037-0600 DEBUG vdl:mains SCOPE thread=0-81-1-32 2012-03-16 13:28:35,037-0600 DEBUG vdl:mains SCOPE thread=0-81-1-37 2012-03-16 13:28:35,038-0600 DEBUG vdl:mains SCOPE thread=0-81-1-2 2012-03-16 13:28:35,038-0600 DEBUG vdl:mains SCOPE thread=0-81-1-9 2012-03-16 13:28:35,038-0600 DEBUG vdl:mains SCOPE thread=0-81-1-16 2012-03-16 13:28:35,038-0600 DEBUG vdl:mains SCOPE thread=0-81-1-15 2012-03-16 13:28:35,038-0600 DEBUG vdl:mains SCOPE thread=0-81-1-7 2012-03-16 13:28:35,039-0600 INFO SetFieldValue Set: swift#callintermediate=0001 2012-03-16 13:28:35,039-0600 DEBUG vdl:mains SCOPE thread=0-81-1-20 2012-03-16 13:28:35,040-0600 DEBUG vdl:mains SCOPE thread=0-81-1-5 2012-03-16 13:28:35,040-0600 DEBUG vdl:mains SCOPE thread=0-81-1-4 2012-03-16 13:28:35,040-0600 DEBUG vdl:mains SCOPE thread=0-81-1-10 2012-03-16 13:28:35,041-0600 DEBUG vdl:mains SCOPE thread=0-81-1-12 2012-03-16 13:28:35,041-0600 DEBUG vdl:mains SCOPE thread=0-81-1-13 2012-03-16 13:28:35,041-0600 DEBUG vdl:mains SCOPE thread=0-81-1-19 2012-03-16 13:28:35,042-0600 DEBUG vdl:mains SCOPE thread=0-81-1-14 2012-03-16 13:28:35,042-0600 DEBUG vdl:mains SCOPE thread=0-81-1-11 2012-03-16 13:28:35,043-0600 DEBUG vdl:mains SCOPE thread=0-81-1-21 2012-03-16 13:28:35,043-0600 DEBUG vdl:mains SCOPE thread=0-81-1-22 2012-03-16 13:28:35,044-0600 DEBUG vdl:mains SCOPE thread=0-81-1-34 2012-03-16 13:28:35,044-0600 DEBUG vdl:mains SCOPE thread=0-81-1-23 2012-03-16 13:28:35,046-0600 DEBUG vdl:mains SCOPE thread=0-81-1-3 2012-03-16 13:28:35,046-0600 DEBUG vdl:mains SCOPE thread=0-81-1-26 2012-03-16 13:28:35,047-0600 DEBUG vdl:mains SCOPE thread=0-81-1-31 2012-03-16 13:28:35,047-0600 DEBUG vdl:mains SCOPE thread=0-81-1-6 2012-03-16 13:28:35,047-0600 DEBUG vdl:mains SCOPE thread=0-81-1-8 2012-03-16 13:28:35,048-0600 DEBUG vdl:mains SCOPE thread=0-81-1-1 2012-03-16 13:28:35,044-0600 DEBUG vdl:mains SCOPE thread=0-81-1-29 2012-03-16 13:28:35,047-0600 DEBUG vdl:mains SCOPE thread=0-81-1-36 2012-03-16 13:28:35,046-0600 DEBUG vdl:mains SCOPE thread=0-81-1-27 2012-03-16 13:28:35,046-0600 DEBUG vdl:mains SCOPE thread=0-81-1-28 2012-03-16 13:28:35,046-0600 INFO yearprint ENDCOMPOUND thread=0-72-1-2 2012-03-16 13:28:35,046-0600 DEBUG vdl:mains SCOPE thread=0-81-1-25 2012-03-16 13:28:35,046-0600 DEBUG vdl:mains SCOPE thread=0-81-1-24 2012-03-16 13:28:35,096-0600 DEBUG zonal_average PROCEDURE line=128 thread=0-77-2 name=zonal_average 2012-03-16 13:28:35,099-0600 DEBUG zonal_average PROCEDURE line=128 thread=0-77-4 name=zonal_average 2012-03-16 13:28:35,115-0600 INFO vdl:execute START thread=0-65 tr=Check_yavg_hsi_csh 2012-03-16 13:28:35,145-0600 DEBUG record_average_files PROCEDURE line=92 thread=0-72-1 name=Record_Average_Files 2012-03-16 13:28:35,149-0600 DEBUG append_ncks_za PROCEDURE line=133 thread=0-77-3 name=Append_ncks_za 2012-03-16 13:28:35,182-0600 DEBUG create_blank_file_f3 PROCEDURE line=215 thread=0-64-1 name=create_blank_file_f3 2012-03-16 13:28:35,300-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.376 2012-03-16 13:28:35,308-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-8-1 name=ncl_wrapper 2012-03-16 13:28:35,307-0600 INFO vdl:execute START thread=0-63-2 tr=dummy_file_csh 2012-03-16 13:28:35,310-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-1-1 name=ncl_wrapper 2012-03-16 13:28:35,310-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-31-1 name=ncl_wrapper 2012-03-16 13:28:35,311-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-12-1 name=ncl_wrapper 2012-03-16 13:28:35,305-0600 INFO vdl:execute START thread=0-63-0 tr=dummy_file_csh 2012-03-16 13:28:35,306-0600 INFO vdl:execute START thread=0-56 tr=check_hsi_mfiles_csh 2012-03-16 13:28:35,306-0600 INFO vdl:execute START thread=0-63-1 tr=dummy_file_csh 2012-03-16 13:28:35,306-0600 INFO vdl:execute START thread=0-63-3-1 tr=dummy_file_csh 2012-03-16 13:28:35,312-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-3-1 name=ncl_wrapper 2012-03-16 13:28:35,310-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-22-1 name=ncl_wrapper 2012-03-16 13:28:35,312-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-18-1 name=ncl_wrapper 2012-03-16 13:28:35,310-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-28-1 name=ncl_wrapper 2012-03-16 13:28:35,312-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-4-1 name=ncl_wrapper 2012-03-16 13:28:35,312-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-17-1 name=ncl_wrapper 2012-03-16 13:28:35,312-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-19-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-33-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-26-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-21-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-2-1 name=ncl_wrapper 2012-03-16 13:28:35,312-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-14-1 name=ncl_wrapper 2012-03-16 13:28:35,311-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-20-1 name=ncl_wrapper 2012-03-16 13:28:35,311-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-24-1 name=ncl_wrapper 2012-03-16 13:28:35,311-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-11-1 name=ncl_wrapper 2012-03-16 13:28:35,316-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-36-1 name=ncl_wrapper 2012-03-16 13:28:35,316-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-32-1 name=ncl_wrapper 2012-03-16 13:28:35,316-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-23-1 name=ncl_wrapper 2012-03-16 13:28:35,311-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-34-1 name=ncl_wrapper 2012-03-16 13:28:35,318-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-35-1 name=ncl_wrapper 2012-03-16 13:28:35,318-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-16-1 name=ncl_wrapper 2012-03-16 13:28:35,319-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-7-1 name=ncl_wrapper 2012-03-16 13:28:35,319-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-25-1 name=ncl_wrapper 2012-03-16 13:28:35,319-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-5-1 name=ncl_wrapper 2012-03-16 13:28:35,320-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-15-1 name=ncl_wrapper 2012-03-16 13:28:35,311-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-0-1 name=ncl_wrapper 2012-03-16 13:28:35,317-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-29-1 name=ncl_wrapper 2012-03-16 13:28:35,316-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-6-1 name=ncl_wrapper 2012-03-16 13:28:35,316-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-37-1 name=ncl_wrapper 2012-03-16 13:28:35,315-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-10-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-30-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-9-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-13-1 name=ncl_wrapper 2012-03-16 13:28:35,314-0600 DEBUG ncl_wrapper PROCEDURE line=169 thread=0-81-1-27-1 name=ncl_wrapper 2012-03-16 13:28:35,330-0600 INFO GlobalSubmitQueue No global submit throttle set. Using default (1024) 2012-03-16 13:28:35,366-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.376 2012-03-16 13:28:35,368-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.376 2012-03-16 13:28:35,369-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.376 2012-03-16 13:28:35,386-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:35,775-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:35,886-0600 INFO vdl:initshareddir START host=localhost - Initializing shared directory 2012-03-16 13:28:35,892-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,377-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,480-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,485-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,498-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,500-0600 INFO vdl:initshareddir END host=localhost - Done initializing shared directory 2012-03-16 13:28:36,502-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=dummy_file_csh-fzzvfjok thread=0-63-3-1-1 host=localhost replicationGroup=ezzvfjok 2012-03-16 13:28:36,502-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=dummy_file_csh-gzzvfjok thread=0-63-1-1 host=localhost replicationGroup=dzzvfjok 2012-03-16 13:28:36,502-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=dummy_file_csh-hzzvfjok thread=0-63-0-1 host=localhost replicationGroup=azzvfjok 2012-03-16 13:28:36,502-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=Check_yavg_hsi_csh-izzvfjok thread=0-65-1 host=localhost replicationGroup=9zzvfjok 2012-03-16 13:28:36,508-0600 INFO vdl:createdirset START jobid=dummy_file_csh-fzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:36,509-0600 INFO vdl:createdirset START jobid=dummy_file_csh-gzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:36,509-0600 INFO vdl:createdirset START jobid=dummy_file_csh-hzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:36,509-0600 INFO vdl:createdirset START jobid=Check_yavg_hsi_csh-izzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:36,510-0600 INFO vdl:createdirs START path=_concurrent dir=popdiag-20120316-1328-l17ob5i5/shared - Creating directory structure 2012-03-16 13:28:36,514-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,515-0600 INFO vdl:createdirset END jobid=dummy_file_csh-gzzvfjok - Done initializing directory structure 2012-03-16 13:28:36,515-0600 INFO vdl:createdirset END jobid=dummy_file_csh-hzzvfjok - Done initializing directory structure 2012-03-16 13:28:36,516-0600 INFO vdl:createdirset END jobid=Check_yavg_hsi_csh-izzvfjok - Done initializing directory structure 2012-03-16 13:28:36,516-0600 INFO vdl:createdirset END jobid=dummy_file_csh-fzzvfjok - Done initializing directory structure 2012-03-16 13:28:36,516-0600 INFO vdl:dostagein START jobid=dummy_file_csh-gzzvfjok - Staging in files 2012-03-16 13:28:36,516-0600 INFO vdl:dostagein START jobid=dummy_file_csh-hzzvfjok - Staging in files 2012-03-16 13:28:36,516-0600 INFO vdl:dostagein START jobid=Check_yavg_hsi_csh-izzvfjok - Staging in files 2012-03-16 13:28:36,516-0600 INFO vdl:dostagein START jobid=dummy_file_csh-fzzvfjok - Staging in files 2012-03-16 13:28:36,525-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,527-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,534-0600 DEBUG vdl:dostageinfile FILE_STAGE_IN_START file=fs.data srchost=localhost srcdir=/glade/home/mickelso/omwg/rundir srcname=fs.data desthost=localhost destdir=popdiag-20120316-1328-l17ob5i5/shared provider=file policy=DEFAULT 2012-03-16 13:28:36,536-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:36,538-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,561-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,563-0600 DEBUG vdl:dostageinfile FILE_STAGE_IN_END file=fs.data srchost=localhost srcdir=/glade/home/mickelso/omwg/rundir srcname=fs.data desthost=localhost destdir=popdiag-20120316-1328-l17ob5i5/shared provider=file 2012-03-16 13:28:36,574-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,576-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,578-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,578-0600 DEBUG vdl:dostageinfile FILE_STAGE_IN_START file=cdm.pl srchost=localhost srcdir=/glade/home/mickelso/swift-0.93RC4/bin/../libexec srcname=cdm.pl desthost=localhost destdir=popdiag-20120316-1328-l17ob5i5/shared provider=file policy=DEFAULT 2012-03-16 13:28:36,580-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,608-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,608-0600 DEBUG vdl:dostageinfile FILE_STAGE_IN_END file=cdm.pl srchost=localhost srcdir=/glade/home/mickelso/swift-0.93RC4/bin/../libexec srcname=cdm.pl desthost=localhost destdir=popdiag-20120316-1328-l17ob5i5/shared provider=file 2012-03-16 13:28:36,619-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,620-0600 DEBUG vdl:dostageinfile FILE_STAGE_IN_START file=cdm_lib.sh srchost=localhost srcdir=/glade/home/mickelso/swift-0.93RC4/bin/../libexec srcname=cdm_lib.sh desthost=localhost destdir=popdiag-20120316-1328-l17ob5i5/shared provider=file policy=DEFAULT 2012-03-16 13:28:36,621-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,628-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,628-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,633-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:36,634-0600 DEBUG vdl:dostageinfile FILE_STAGE_IN_END file=cdm_lib.sh srchost=localhost srcdir=/glade/home/mickelso/swift-0.93RC4/bin/../libexec srcname=cdm_lib.sh desthost=localhost destdir=popdiag-20120316-1328-l17ob5i5/shared provider=file 2012-03-16 13:28:36,635-0600 INFO vdl:dostagein END jobid=dummy_file_csh-gzzvfjok - Staging in finished 2012-03-16 13:28:36,635-0600 INFO vdl:dostagein END jobid=dummy_file_csh-fzzvfjok - Staging in finished 2012-03-16 13:28:36,635-0600 INFO vdl:dostagein END jobid=Check_yavg_hsi_csh-izzvfjok - Staging in finished 2012-03-16 13:28:36,635-0600 INFO vdl:dostagein END jobid=dummy_file_csh-hzzvfjok - Staging in finished 2012-03-16 13:28:36,637-0600 DEBUG vdl:execute2 JOB_START jobid=dummy_file_csh-gzzvfjok tr=dummy_file_csh arguments=[_concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006-] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/g/dummy_file_csh-gzzvfjok host=localhost 2012-03-16 13:28:36,637-0600 DEBUG vdl:execute2 JOB_START jobid=dummy_file_csh-fzzvfjok tr=dummy_file_csh arguments=[_concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d-] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/f/dummy_file_csh-fzzvfjok host=localhost 2012-03-16 13:28:36,637-0600 DEBUG vdl:execute2 JOB_START jobid=Check_yavg_hsi_csh-izzvfjok tr=Check_yavg_hsi_csh arguments=[/home/mickelso/cesm/, c40.t62x1.verif.03, 1.0, 10.0, /glade/scratch/mickelso/omwg-swift/swiftSandbox2/, _concurrent/yavg_hsi_list-69d9352b-056f-4a9a-ae9d-90ca2347dcc5-] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/i/Check_yavg_hsi_csh-izzvfjok host=localhost 2012-03-16 13:28:36,637-0600 DEBUG vdl:execute2 JOB_START jobid=dummy_file_csh-hzzvfjok tr=dummy_file_csh arguments=[_concurrent/mavgFileD-f961ba84-c207-4877-9c78-bf02d2ac367f-] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/h/dummy_file_csh-hzzvfjok host=localhost 2012-03-16 13:28:36,642-0600 DEBUG textfiles BEGIN TC: #This is the transformation catalog. # #It comes pre-configured with a number of simple transformations with #paths that are likely to work on a linux box. However, on some systems, #the paths to these executables will be different (for example, sometimes #some of these programs are found in /usr/bin rather than in /bin) # #NOTE WELL: fields in this file must be separated by tabs, not spaces; and #there must be no trailing whitespace at the end of each line. # # sitename transformation path INSTALLED platform profiles localhost echo /bin/echo INSTALLED INTEL32::LINUX null localhost cat /bin/cat INSTALLED INTEL32::LINUX null localhost ls /bin/ls INSTALLED INTEL32::LINUX null localhost grep /bin/grep INSTALLED INTEL32::LINUX null localhost sort /bin/sort INSTALLED INTEL32::LINUX null localhost paste /bin/paste INSTALLED INTEL32::LINUX null localhost pwd /bin/pwd INSTALLED INTEL32::LINUX null localhost bash /bin/bash INSTALLED INTEL32::LINUX null localhost pwd /bin/pwd INSTALLED INTEL32::LINUX null localhost touch /bin/touch INSTALLED INTEL32::LINUX null localhost cp /bin/cp INSTALLED INTEL32::LINUX null localhost mv /bin/mv INSTALLED INTEL32::LINUX null localhost printf /usr/bin/printf INSTALLED INTEL32::LINUX null localhost ncks /fs/local/bin/ncks INSTALLED INTEL32::LINUX null localhost ncea /fs/local/bin/ncea INSTALLED INTEL32::LINUX null localhost ncatted /fs/local/bin//ncatted INSTALLED INTEL32::LINUX null localhost ncflint /fs/local/bin/ncflint INSTALLED INTEL32::LINUX null localhost ncrcat /fs/local/bin/ncrcat INSTALLED INTEL32::LINUX null localhost ncra /fs/local/bin/ncra INSTALLED INTEL32::LINUX null localhost hsi /fs/local/bin/hsi INSTALLED INTEL32::LINUX null localhost za /glade/home/mickelso/zon_avg/za INSTALLED INTEL32::LINUX null localhost get_hsi_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh INSTALLED INTEL32::LINUX null localhost get_hsi_file_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi_file.csh INSTALLED INTEL32::LINUX null localhost Check_yavg_hsi_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/Check_yavg_hsi.csh INSTALLED INTEL32::LINUX null localhost Check_yavg_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/Check_yavg.csh INSTALLED INTEL32::LINUX null localhost store_file_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/store_file.csh INSTALLED INTEL32::LINUX null localhost Append_ncks_za_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/Append_ncks_za.csh INSTALLED INTEL32::LINUX null localhost ncl_wrapper_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncl_wrapper.csh INSTALLED INTEL32::LINUX null localhost get_PSList_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/get_PSList.csh INSTALLED INTEL32::LINUX null localhost convert_images_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/convert_images.csh INSTALLED INTEL32::LINUX null localhost check_hsi_mfiles_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/check_hsi_mfiles.csh INSTALLED INTEL32::LINUX null localhost dummy_file_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh INSTALLED INTEL32::LINUX null localhost ncwa_wrap_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncwa_wrap.csh INSTALLED INTEL32::LINUX null localhost add_rms_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/add_rms.csh INSTALLED INTEL32::LINUX null localhost hor_mean_diff_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/hor_mean_diff.csh INSTALLED INTEL32::LINUX null localhost ncrcat_wrap_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncrcat_wrap.csh INSTALLED INTEL32::LINUX null localhost ncdiff_wrap_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncdiff_wrap.csh INSTALLED INTEL32::LINUX null localhost ts_meandiff_obs_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ts_meandiff_obs.csh INSTALLED INTEL32::LINUX null localhost ncea_wrapper_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncea_wrapper.csh INSTALLED INTEL32::LINUX null localhost ncra_wrapper_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncra_wrapper.csh INSTALLED INTEL32::LINUX null localhost ncea_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncea.csh INSTALLED INTEL32::LINUX null localhost unzipLogs_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/unzipLogs.csh INSTALLED INTEL32::LINUX null localhost get_hsi_logfile_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi_logfile.csh INSTALLED INTEL32::LINUX null localhost check_hsi_logfile_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/check_hsi_logfile.csh INSTALLED INTEL32::LINUX null localhost proc_cpllog_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/proc_cpllog.csh INSTALLED INTEL32::LINUX null localhost proc_ypoplog_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/proc_ypoplog.csh INSTALLED INTEL32::LINUX null localhost ncl_wrapper_diff_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/ncl_wrapper_diff.csh INSTALLED INTEL32::LINUX null localhost removeMF_csh /glade/home/mickelso/omwg/popdiag/swiftUtils/removeMF.csh INSTALLED INTEL32::LINUX null localhost sleep /bin/sleep null null null pbs cat /bin/cat null null GLOBUS::maxwalltime="01:00:00" 2012-03-16 13:28:36,642-0600 DEBUG textfiles END TC: 2012-03-16 13:28:36,822-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-63-0-1-1331926111052) is /bin/bash shared/_swiftwrap dummy_file_csh-hzzvfjok -jobdir h -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgFileD-f961ba84-c207-4877-9c78-bf02d2ac367f- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgFileD-f961ba84-c207-4877-9c78-bf02d2ac367f- 2012-03-16 13:28:36,925-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-63-3-1-1-1331926111054) is /bin/bash shared/_swiftwrap dummy_file_csh-fzzvfjok -jobdir f -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- 2012-03-16 13:28:36,926-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-63-1-1-1331926111057) is /bin/bash shared/_swiftwrap dummy_file_csh-gzzvfjok -jobdir g -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006- 2012-03-16 13:28:36,927-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-65-1-1331926111058) is /bin/bash shared/_swiftwrap Check_yavg_hsi_csh-izzvfjok -jobdir i -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/Check_yavg_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/yavg_hsi_list-69d9352b-056f-4a9a-ae9d-90ca2347dcc5- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a /home/mickelso/cesm/ c40.t62x1.verif.03 1 10 /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavg_hsi_list-69d9352b-056f-4a9a-ae9d-90ca2347dcc5- 2012-03-16 13:28:36,936-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap dummy_file_csh-hzzvfjok -jobdir h -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgFileD-f961ba84-c207-4877-9c78-bf02d2ac367f- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgFileD-f961ba84-c207-4877-9c78-bf02d2ac367f- 2012-03-16 13:28:36,940-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap dummy_file_csh-fzzvfjok -jobdir f -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- 2012-03-16 13:28:36,943-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap dummy_file_csh-gzzvfjok -jobdir g -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006- 2012-03-16 13:28:36,962-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap Check_yavg_hsi_csh-izzvfjok -jobdir i -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/Check_yavg_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/yavg_hsi_list-69d9352b-056f-4a9a-ae9d-90ca2347dcc5- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a /home/mickelso/cesm/ c40.t62x1.verif.03 1 10 /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavg_hsi_list-69d9352b-056f-4a9a-ae9d-90ca2347dcc5- 2012-03-16 13:28:38,141-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:38,146-0600 DEBUG vdl:execute2 STAGING_OUT jobid=dummy_file_csh-gzzvfjok 2012-03-16 13:28:38,150-0600 INFO vdl:dostageout START jobid=dummy_file_csh-gzzvfjok - Staging out files 2012-03-16 13:28:38,150-0600 DEBUG vdl:dostageout stageouts: [[$, mavgTFileD:file - Open]] 2012-03-16 13:28:38,153-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:38,162-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:38,192-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:38,195-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:38,199-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:38,201-0600 INFO vdl:dostageout END jobid=dummy_file_csh-gzzvfjok - Staging out finished 2012-03-16 13:28:38,251-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:38,253-0600 DEBUG vdl:execute2 JOB_END jobid=dummy_file_csh-gzzvfjok 2012-03-16 13:28:38,256-0600 INFO vdl:execute END_SUCCESS thread=0-63-1 tr=dummy_file_csh 2012-03-16 13:28:38,323-0600 DEBUG create_blank_file PROCEDURE_END line=205 2012-03-16 13:28:38,754-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.464 2012-03-16 13:28:38,759-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=check_hsi_mfiles_csh-jzzvfjok thread=0-56-1 host=localhost replicationGroup=bzzvfjok 2012-03-16 13:28:38,767-0600 INFO vdl:createdirset START jobid=check_hsi_mfiles_csh-jzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:38,779-0600 INFO vdl:createdirset END jobid=check_hsi_mfiles_csh-jzzvfjok - Done initializing directory structure 2012-03-16 13:28:38,780-0600 INFO vdl:dostagein START jobid=check_hsi_mfiles_csh-jzzvfjok - Staging in files 2012-03-16 13:28:38,885-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:38,890-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:38,893-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:38,894-0600 INFO vdl:dostagein END jobid=check_hsi_mfiles_csh-jzzvfjok - Staging in finished 2012-03-16 13:28:38,895-0600 DEBUG vdl:execute2 JOB_START jobid=check_hsi_mfiles_csh-jzzvfjok tr=check_hsi_mfiles_csh arguments=[/home/mickelso/cesm/, c40.t62x1.verif.03, 1.0, 10.0, _concurrent/monHSIFileList-126fb71b-ff95-4454-8e3c-d32baa321a8c-, /glade/scratch/mickelso/omwg-swift/swiftSandbox2/, /glade/home/mickelso/omwg/popdiag//tool_lib, 1.0, 0.0] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/j/check_hsi_mfiles_csh-jzzvfjok host=localhost 2012-03-16 13:28:38,902-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-56-1-1331926111076) is /bin/bash shared/_swiftwrap check_hsi_mfiles_csh-jzzvfjok -jobdir j -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/check_hsi_mfiles.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/monHSIFileList-126fb71b-ff95-4454-8e3c-d32baa321a8c- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a /home/mickelso/cesm/ c40.t62x1.verif.03 1 10 _concurrent/monHSIFileList-126fb71b-ff95-4454-8e3c-d32baa321a8c- /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ /glade/home/mickelso/omwg/popdiag//tool_lib 1 0 2012-03-16 13:28:38,903-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap check_hsi_mfiles_csh-jzzvfjok -jobdir j -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/check_hsi_mfiles.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/monHSIFileList-126fb71b-ff95-4454-8e3c-d32baa321a8c- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a /home/mickelso/cesm/ c40.t62x1.verif.03 1 10 _concurrent/monHSIFileList-126fb71b-ff95-4454-8e3c-d32baa321a8c- /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ /glade/home/mickelso/omwg/popdiag//tool_lib 1 0 2012-03-16 13:28:40,194-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:40,210-0600 DEBUG vdl:execute2 STAGING_OUT jobid=dummy_file_csh-fzzvfjok 2012-03-16 13:28:40,215-0600 INFO vdl:dostageout START jobid=dummy_file_csh-fzzvfjok - Staging out files 2012-03-16 13:28:40,217-0600 DEBUG vdl:dostageout stageouts: [[$, mavgRFile:file - Open]] 2012-03-16 13:28:40,272-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:40,274-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:40,280-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:40,286-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:40,287-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:40,290-0600 INFO vdl:dostageout END jobid=dummy_file_csh-fzzvfjok - Staging out finished 2012-03-16 13:28:40,298-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:40,303-0600 DEBUG vdl:execute2 JOB_END jobid=dummy_file_csh-fzzvfjok 2012-03-16 13:28:40,305-0600 INFO vdl:execute END_SUCCESS thread=0-63-3-1 tr=dummy_file_csh 2012-03-16 13:28:40,306-0600 DEBUG create_blank_file PROCEDURE_END line=205 2012-03-16 13:28:40,747-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:40,749-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.550 2012-03-16 13:28:40,751-0600 DEBUG vdl:execute2 STAGING_OUT jobid=dummy_file_csh-hzzvfjok 2012-03-16 13:28:40,752-0600 INFO vdl:dostageout START jobid=dummy_file_csh-hzzvfjok - Staging out files 2012-03-16 13:28:40,754-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=dummy_file_csh-kzzvfjok thread=0-63-2-1 host=localhost replicationGroup=czzvfjok 2012-03-16 13:28:40,756-0600 INFO vdl:createdirset START jobid=dummy_file_csh-kzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:40,757-0600 INFO vdl:createdirset END jobid=dummy_file_csh-kzzvfjok - Done initializing directory structure 2012-03-16 13:28:40,757-0600 INFO vdl:dostagein START jobid=dummy_file_csh-kzzvfjok - Staging in files 2012-03-16 13:28:40,758-0600 DEBUG vdl:dostageout stageouts: [[$, mavgFileD:file - Open]] 2012-03-16 13:28:40,761-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:40,763-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=mavgFileD-f961ba84-c207-4877-9c78-bf02d2ac367f- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:40,764-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:40,764-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:40,768-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:40,770-0600 INFO vdl:dostagein END jobid=dummy_file_csh-kzzvfjok - Staging in finished 2012-03-16 13:28:40,771-0600 DEBUG vdl:execute2 JOB_START jobid=dummy_file_csh-kzzvfjok tr=dummy_file_csh arguments=[_concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046-] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/k/dummy_file_csh-kzzvfjok host=localhost 2012-03-16 13:28:40,772-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:40,773-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=mavgFileD-f961ba84-c207-4877-9c78-bf02d2ac367f- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:40,776-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-63-2-1-1331926111101) is /bin/bash shared/_swiftwrap dummy_file_csh-kzzvfjok -jobdir k -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046- 2012-03-16 13:28:40,802-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap dummy_file_csh-kzzvfjok -jobdir k -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if -of _concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046- 2012-03-16 13:28:40,835-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:40,868-0600 INFO vdl:dostageout END jobid=dummy_file_csh-hzzvfjok - Staging out finished 2012-03-16 13:28:40,883-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:40,885-0600 DEBUG vdl:execute2 JOB_END jobid=dummy_file_csh-hzzvfjok 2012-03-16 13:28:40,886-0600 INFO vdl:execute END_SUCCESS thread=0-63-0 tr=dummy_file_csh 2012-03-16 13:28:40,888-0600 DEBUG create_blank_file PROCEDURE_END line=205 2012-03-16 13:28:46,833-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:46,836-0600 DEBUG vdl:execute2 STAGING_OUT jobid=dummy_file_csh-kzzvfjok 2012-03-16 13:28:46,836-0600 INFO vdl:dostageout START jobid=dummy_file_csh-kzzvfjok - Staging out files 2012-03-16 13:28:46,836-0600 DEBUG vdl:dostageout stageouts: [[$, mavgSFileD:file - Open]] 2012-03-16 13:28:46,837-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:46,839-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:46,845-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:46,846-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:46,850-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:46,852-0600 INFO vdl:dostageout END jobid=dummy_file_csh-kzzvfjok - Staging out finished 2012-03-16 13:28:47,027-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:47,028-0600 DEBUG vdl:execute2 JOB_END jobid=dummy_file_csh-kzzvfjok 2012-03-16 13:28:47,029-0600 INFO vdl:execute END_SUCCESS thread=0-63-2 tr=dummy_file_csh 2012-03-16 13:28:47,029-0600 DEBUG create_blank_file PROCEDURE_END line=205 2012-03-16 13:28:47,032-0600 INFO vdl:execute START thread=0-64-1 tr=dummy_file_csh 2012-03-16 13:28:47,035-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.636 2012-03-16 13:28:47,036-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=dummy_file_csh-mzzvfjok thread=0-64-1-1 host=localhost replicationGroup=lzzvfjok 2012-03-16 13:28:47,037-0600 INFO vdl:createdirset START jobid=dummy_file_csh-mzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:47,038-0600 INFO vdl:createdirset END jobid=dummy_file_csh-mzzvfjok - Done initializing directory structure 2012-03-16 13:28:47,038-0600 INFO vdl:dostagein START jobid=dummy_file_csh-mzzvfjok - Staging in files 2012-03-16 13:28:47,039-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:47,045-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:47,053-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:47,060-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:47,060-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:47,061-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:47,063-0600 DEBUG vdl:dostagein CDM: file://localhost/_concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006- : DEFAULT 2012-03-16 13:28:47,063-0600 DEBUG vdl:dostagein CDM: file://localhost/_concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- : DEFAULT 2012-03-16 13:28:47,063-0600 DEBUG vdl:dostagein CDM: file://localhost/_concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046- : DEFAULT 2012-03-16 13:28:47,064-0600 INFO vdl:dostagein END jobid=dummy_file_csh-mzzvfjok - Staging in finished 2012-03-16 13:28:47,065-0600 DEBUG vdl:execute2 JOB_START jobid=dummy_file_csh-mzzvfjok tr=dummy_file_csh arguments=[_concurrent/MAVG_complete-0b6f37a2-10f0-4333-8e98-96609da3a338-] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/m/dummy_file_csh-mzzvfjok host=localhost 2012-03-16 13:28:47,066-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-64-1-1-1331926111129) is /bin/bash shared/_swiftwrap dummy_file_csh-mzzvfjok -jobdir m -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if _concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006-|_concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046-|_concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- -of _concurrent/MAVG_complete-0b6f37a2-10f0-4333-8e98-96609da3a338- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/MAVG_complete-0b6f37a2-10f0-4333-8e98-96609da3a338- 2012-03-16 13:28:47,067-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap dummy_file_csh-mzzvfjok -jobdir m -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/dummy_file.csh -out stdout.txt -err stderr.txt -i -d _concurrent -if _concurrent/mavgTFileD-ae5a32ba-2cc9-453c-869c-b3241610d006-|_concurrent/mavgSFileD-9526e5de-5f15-426c-b4a9-711f0d8c9046-|_concurrent/mavgRFile-3369f571-252a-4394-82b0-cb958d030a2d- -of _concurrent/MAVG_complete-0b6f37a2-10f0-4333-8e98-96609da3a338- -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a _concurrent/MAVG_complete-0b6f37a2-10f0-4333-8e98-96609da3a338- 2012-03-16 13:28:48,529-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:48,537-0600 DEBUG vdl:execute2 STAGING_OUT jobid=Check_yavg_hsi_csh-izzvfjok 2012-03-16 13:28:48,539-0600 INFO vdl:dostageout START jobid=Check_yavg_hsi_csh-izzvfjok - Staging out files 2012-03-16 13:28:48,539-0600 DEBUG vdl:dostageout stageouts: [[$, yavg_hsi_list:file - Open]] 2012-03-16 13:28:48,540-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=yavg_hsi_list-69d9352b-056f-4a9a-ae9d-90ca2347dcc5- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:48,542-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:48,552-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:48,554-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=yavg_hsi_list-69d9352b-056f-4a9a-ae9d-90ca2347dcc5- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:48,559-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:48,561-0600 INFO vdl:dostageout END jobid=Check_yavg_hsi_csh-izzvfjok - Staging out finished 2012-03-16 13:28:48,609-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:48,612-0600 DEBUG vdl:execute2 JOB_END jobid=Check_yavg_hsi_csh-izzvfjok 2012-03-16 13:28:48,613-0600 INFO vdl:execute END_SUCCESS thread=0-65 tr=Check_yavg_hsi_csh 2012-03-16 13:28:48,615-0600 DEBUG check_yavg_hsi PROCEDURE_END line=149 2012-03-16 13:28:49,052-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-66 2012-03-16 13:28:49,054-0600 DEBUG hsi_files FOREACH_IT_START line=260 thread=0-67-1-1-1 2012-03-16 13:28:49,055-0600 DEBUG hsi_files FOREACH_IT_START line=260 thread=0-67-1-1-4 2012-03-16 13:28:49,055-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-1 2012-03-16 13:28:49,055-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-4 2012-03-16 13:28:49,059-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-1-1 2012-03-16 13:28:49,061-0600 DEBUG gethsifile PROCEDURE line=144 thread=0-67-1-1-1-1-1 name=GethsiFile 2012-03-16 13:28:49,066-0600 DEBUG hsi_files FOREACH_IT_START line=260 thread=0-67-1-1-2 2012-03-16 13:28:49,066-0600 DEBUG hsi_files FOREACH_IT_START line=260 thread=0-67-1-1-0 2012-03-16 13:28:49,066-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-2 2012-03-16 13:28:49,067-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-0 2012-03-16 13:28:49,067-0600 DEBUG hsi_files FOREACH_IT_START line=260 thread=0-67-1-1-3 2012-03-16 13:28:49,067-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-3 2012-03-16 13:28:49,069-0600 INFO vdl:execute START thread=0-67-1-1-1-1-1 tr=get_hsi_csh 2012-03-16 13:28:49,073-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.685 2012-03-16 13:28:49,074-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-2-1 2012-03-16 13:28:49,074-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-4-1 2012-03-16 13:28:49,074-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-0-1 2012-03-16 13:28:49,074-0600 DEBUG hsi_files SCOPE thread=0-67-1-1-3-1 2012-03-16 13:28:49,076-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=get_hsi_csh-ozzvfjok thread=0-67-1-1-1-1-1-1 host=localhost replicationGroup=nzzvfjok 2012-03-16 13:28:49,077-0600 INFO vdl:createdirset START jobid=get_hsi_csh-ozzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:49,081-0600 INFO vdl:createdirs START path=_concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ dir=popdiag-20120316-1328-l17ob5i5/shared - Creating directory structure 2012-03-16 13:28:49,084-0600 DEBUG gethsifile PROCEDURE line=144 thread=0-67-1-1-0-1-1 name=GethsiFile 2012-03-16 13:28:49,083-0600 DEBUG gethsifile PROCEDURE line=144 thread=0-67-1-1-4-1-1 name=GethsiFile 2012-03-16 13:28:49,086-0600 DEBUG gethsifile PROCEDURE line=144 thread=0-67-1-1-3-1-1 name=GethsiFile 2012-03-16 13:28:49,086-0600 DEBUG gethsifile PROCEDURE line=144 thread=0-67-1-1-2-1-1 name=GethsiFile 2012-03-16 13:28:49,088-0600 INFO LateBindingScheduler JobQueue: 0 2012-03-16 13:28:49,090-0600 INFO vdl:createdirset END jobid=get_hsi_csh-ozzvfjok - Done initializing directory structure 2012-03-16 13:28:49,092-0600 INFO vdl:execute START thread=0-67-1-1-4-1-1 tr=get_hsi_csh 2012-03-16 13:28:49,092-0600 INFO vdl:execute START thread=0-67-1-1-2-1-1 tr=get_hsi_csh 2012-03-16 13:28:49,093-0600 INFO vdl:execute START thread=0-67-1-1-0-1-1 tr=get_hsi_csh 2012-03-16 13:28:49,094-0600 INFO vdl:execute START thread=0-67-1-1-3-1-1 tr=get_hsi_csh 2012-03-16 13:28:49,099-0600 INFO vdl:dostagein START jobid=get_hsi_csh-ozzvfjok - Staging in files 2012-03-16 13:28:49,099-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.697 2012-03-16 13:28:49,102-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=get_hsi_csh-tzzvfjok thread=0-67-1-1-2-1-1-1 host=localhost replicationGroup=pzzvfjok 2012-03-16 13:28:49,103-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:49,105-0600 INFO vdl:createdirset START jobid=get_hsi_csh-tzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:49,106-0600 INFO vdl:createdirset END jobid=get_hsi_csh-tzzvfjok - Done initializing directory structure 2012-03-16 13:28:49,106-0600 INFO vdl:dostagein START jobid=get_hsi_csh-tzzvfjok - Staging in files 2012-03-16 13:28:49,107-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:49,108-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:49,110-0600 INFO LateBindingScheduler JobQueue: 4 2012-03-16 13:28:49,110-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:49,110-0600 INFO vdl:dostagein END jobid=get_hsi_csh-ozzvfjok - Staging in finished 2012-03-16 13:28:49,111-0600 DEBUG vdl:execute2 JOB_START jobid=get_hsi_csh-ozzvfjok tr=get_hsi_csh arguments=[c40.t62x1.verif.03.pop.h.0001.nc, /glade/scratch/mickelso/omwg-swift/swiftSandbox2/, _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-1, /home/mickelso/cesm/, c40.t62x1.verif.03, year] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/o/get_hsi_csh-ozzvfjok host=localhost 2012-03-16 13:28:49,113-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:49,113-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-67-1-1-1-1-1-1-1331926111160) is /bin/bash shared/_swiftwrap get_hsi_csh-ozzvfjok -jobdir o -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-1 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a c40.t62x1.verif.03.pop.h.0001.nc /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-1 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:28:49,114-0600 INFO vdl:dostagein END jobid=get_hsi_csh-tzzvfjok - Staging in finished 2012-03-16 13:28:49,114-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap get_hsi_csh-ozzvfjok -jobdir o -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-1 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a c40.t62x1.verif.03.pop.h.0001.nc /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-1 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:28:49,114-0600 DEBUG vdl:execute2 JOB_START jobid=get_hsi_csh-tzzvfjok tr=get_hsi_csh arguments=[c40.t62x1.verif.03.pop.h.0007.nc, /glade/scratch/mickelso/omwg-swift/swiftSandbox2/, _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-2, /home/mickelso/cesm/, c40.t62x1.verif.03, year] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/t/get_hsi_csh-tzzvfjok host=localhost 2012-03-16 13:28:49,118-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-67-1-1-2-1-1-1-1331926111162) is /bin/bash shared/_swiftwrap get_hsi_csh-tzzvfjok -jobdir t -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-2 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a c40.t62x1.verif.03.pop.h.0007.nc /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-2 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:28:49,148-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap get_hsi_csh-tzzvfjok -jobdir t -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-2 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a c40.t62x1.verif.03.pop.h.0007.nc /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-2 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:28:50,298-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:50,302-0600 DEBUG vdl:execute2 STAGING_OUT jobid=dummy_file_csh-mzzvfjok 2012-03-16 13:28:50,304-0600 INFO vdl:dostageout START jobid=dummy_file_csh-mzzvfjok - Staging out files 2012-03-16 13:28:50,304-0600 DEBUG vdl:dostageout stageouts: [[$, MAVG_complete:file - Open]] 2012-03-16 13:28:50,305-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=MAVG_complete-0b6f37a2-10f0-4333-8e98-96609da3a338- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:50,307-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:50,316-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:50,317-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=MAVG_complete-0b6f37a2-10f0-4333-8e98-96609da3a338- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:28:50,320-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:50,321-0600 INFO vdl:dostageout END jobid=dummy_file_csh-mzzvfjok - Staging out finished 2012-03-16 13:28:50,329-0600 INFO LateBindingScheduler JobQueue: 3 2012-03-16 13:28:50,334-0600 DEBUG vdl:execute2 JOB_END jobid=dummy_file_csh-mzzvfjok 2012-03-16 13:28:50,335-0600 INFO vdl:execute END_SUCCESS thread=0-64-1 tr=dummy_file_csh 2012-03-16 13:28:50,336-0600 DEBUG create_blank_file_f3 PROCEDURE_END line=215 2012-03-16 13:28:50,833-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.721 2012-03-16 13:28:50,834-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=get_hsi_csh-uzzvfjok thread=0-67-1-1-0-1-1-1 host=localhost replicationGroup=qzzvfjok 2012-03-16 13:28:50,835-0600 INFO vdl:createdirset START jobid=get_hsi_csh-uzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:50,836-0600 INFO vdl:createdirset END jobid=get_hsi_csh-uzzvfjok - Done initializing directory structure 2012-03-16 13:28:50,837-0600 INFO vdl:dostagein START jobid=get_hsi_csh-uzzvfjok - Staging in files 2012-03-16 13:28:50,842-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:50,846-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:50,849-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:50,851-0600 INFO vdl:dostagein END jobid=get_hsi_csh-uzzvfjok - Staging in finished 2012-03-16 13:28:50,851-0600 DEBUG vdl:execute2 JOB_START jobid=get_hsi_csh-uzzvfjok tr=get_hsi_csh arguments=[Blank, /glade/scratch/mickelso/omwg-swift/swiftSandbox2/, _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-0, /home/mickelso/cesm/, c40.t62x1.verif.03, year] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/u/get_hsi_csh-uzzvfjok host=localhost 2012-03-16 13:28:50,853-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-67-1-1-0-1-1-1-1331926111180) is /bin/bash shared/_swiftwrap get_hsi_csh-uzzvfjok -jobdir u -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-0 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a Blank /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-0 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:28:50,854-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap get_hsi_csh-uzzvfjok -jobdir u -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-0 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a Blank /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-0 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:28:52,155-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:52,157-0600 DEBUG vdl:execute2 STAGING_OUT jobid=get_hsi_csh-uzzvfjok 2012-03-16 13:28:52,159-0600 INFO vdl:dostageout START jobid=get_hsi_csh-uzzvfjok - Staging out files 2012-03-16 13:28:52,159-0600 DEBUG vdl:dostageout stageouts: [[$, yavghsiComplete[0]:file - Open]] 2012-03-16 13:28:52,160-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=elt-0 srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ srchost=localhost destdir=_concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ desthost=localhost provider=file 2012-03-16 13:28:52,214-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:52,219-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:52,219-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=elt-0 srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ srchost=localhost destdir=_concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ desthost=localhost provider=file 2012-03-16 13:28:52,221-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:52,223-0600 INFO vdl:dostageout END jobid=get_hsi_csh-uzzvfjok - Staging out finished 2012-03-16 13:28:52,387-0600 INFO LateBindingScheduler JobQueue: 2 2012-03-16 13:28:52,389-0600 DEBUG vdl:execute2 JOB_END jobid=get_hsi_csh-uzzvfjok 2012-03-16 13:28:52,389-0600 INFO vdl:execute END_SUCCESS thread=0-67-1-1-0-1-1 tr=get_hsi_csh 2012-03-16 13:28:52,390-0600 DEBUG gethsifile PROCEDURE_END line=144 2012-03-16 13:28:52,390-0600 DEBUG hsi_files FOREACH_IT_END line=260 thread=0-67-1-1-0 2012-03-16 13:28:52,894-0600 INFO WeightedHostScoreScheduler CONTACT_SELECTED host=localhost, score=86.769 2012-03-16 13:28:52,895-0600 DEBUG vdl:execute2 THREAD_ASSOCIATION jobid=get_hsi_csh-vzzvfjok thread=0-67-1-1-3-1-1-1 host=localhost replicationGroup=rzzvfjok 2012-03-16 13:28:52,897-0600 INFO vdl:createdirset START jobid=get_hsi_csh-vzzvfjok host=localhost - Initializing directory structure 2012-03-16 13:28:52,897-0600 INFO vdl:createdirset END jobid=get_hsi_csh-vzzvfjok - Done initializing directory structure 2012-03-16 13:28:52,897-0600 INFO vdl:dostagein START jobid=get_hsi_csh-vzzvfjok - Staging in files 2012-03-16 13:28:52,899-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:52,936-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:52,946-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:28:52,947-0600 INFO vdl:dostagein END jobid=get_hsi_csh-vzzvfjok - Staging in finished 2012-03-16 13:28:52,948-0600 DEBUG vdl:execute2 JOB_START jobid=get_hsi_csh-vzzvfjok tr=get_hsi_csh arguments=[c40.t62x1.verif.03.pop.h.0008.nc, /glade/scratch/mickelso/omwg-swift/swiftSandbox2/, _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-3, /home/mickelso/cesm/, c40.t62x1.verif.03, year] tmpdir=popdiag-20120316-1328-l17ob5i5/jobs/v/get_hsi_csh-vzzvfjok host=localhost 2012-03-16 13:28:52,950-0600 INFO GridExec TASK_DEFINITION: Task(type=JOB_SUBMISSION, identity=urn:0-67-1-1-3-1-1-1-1331926111198) is /bin/bash shared/_swiftwrap get_hsi_csh-vzzvfjok -jobdir v -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-3 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a c40.t62x1.verif.03.pop.h.0008.nc /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-3 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:28:52,951-0600 INFO JobSubmissionTaskHandler Submit: in: /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5 command: /bin/bash /glade/scratch/mickelso/omwg-swift/swiftSandbox2//popdiag-20120316-1328-l17ob5i5/shared/_swiftwrap get_hsi_csh-vzzvfjok -jobdir v -scratch -e /glade/home/mickelso/omwg/popdiag/swiftUtils/get_hsi.csh -out stdout.txt -err stderr.txt -i -d _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array/ -if -of _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-3 -k -cdmfile /glade/home/mickelso/omwg/rundir/fs.data -status provider -a c40.t62x1.verif.03.pop.h.0008.nc /glade/scratch/mickelso/omwg-swift/swiftSandbox2/ _concurrent/yavghsiComplete-220209a9-41b0-4457-9e2b-412c0957622e--array//elt-3 /home/mickelso/cesm/ c40.t62x1.verif.03 year 2012-03-16 13:29:10,368-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:29:10,390-0600 DEBUG vdl:execute2 STAGING_OUT jobid=check_hsi_mfiles_csh-jzzvfjok 2012-03-16 13:29:10,391-0600 INFO vdl:dostageout START jobid=check_hsi_mfiles_csh-jzzvfjok - Staging out files 2012-03-16 13:29:10,391-0600 DEBUG vdl:dostageout stageouts: [[$, monHSIFileList:file - Open]] 2012-03-16 13:29:10,392-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_START srcname=monHSIFileList-126fb71b-ff95-4454-8e3c-d32baa321a8c- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:29:10,454-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:29:10,461-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:29:10,466-0600 DEBUG vdl:dostageout FILE_STAGE_OUT_END srcname=monHSIFileList-126fb71b-ff95-4454-8e3c-d32baa321a8c- srcdir=popdiag-20120316-1328-l17ob5i5/shared/_concurrent srchost=localhost destdir=_concurrent desthost=localhost provider=file 2012-03-16 13:29:10,468-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:29:10,470-0600 INFO vdl:dostageout END jobid=check_hsi_mfiles_csh-jzzvfjok - Staging out finished 2012-03-16 13:29:10,590-0600 INFO LateBindingScheduler JobQueue: 1 2012-03-16 13:29:10,592-0600 DEBUG vdl:execute2 JOB_END jobid=check_hsi_mfiles_csh-jzzvfjok 2012-03-16 13:29:10,594-0600 INFO vdl:execute END_SUCCESS thread=0-56 tr=check_hsi_mfiles_csh 2012-03-16 13:29:10,595-0600 DEBUG check_mon_hsi PROCEDURE_END line=138 2012-03-16 13:29:10,598-0600 DEBUG vdl:mains INTERNALPROC_END thread=0-57 2012-03-16 13:29:10,605-0600 DEBUG vdl:mains FOREACH_IT_START line=458 thread=0-79-1 2012-03-16 13:29:10,605-0600 DEBUG vdl:mains SCOPE thread=0-79-1 2012-03-16 13:29:10,608-0600 DEBUG removemf PROCEDURE line=164 thread=0-79-0-1 name=removeMF 2012-03-16 13:29:10,613-0600 DEBUG removemf PROCEDURE line=164 thread=0-79-1-1 name=removeMF 2012-03-16 13:29:10,702-0600 DEBUG VDL2ExecutionContext First operand is null First operand is null at org.griphyn.vdl.karajan.lib.Operators.vdlop_eq(Operators.java:181) at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:616) at org.globus.cog.karajan.workflow.nodes.functions.FunctionsCollection.function(FunctionsCollection.java:82) at org.globus.cog.karajan.workflow.nodes.functions.AbstractFunction.post(AbstractFunction.java:27) at org.globus.cog.karajan.workflow.nodes.AbstractSequentialWithArguments.futureModified(AbstractSequentialWithArguments.java:208) at org.griphyn.vdl.karajan.DSHandleFutureWrapper$1.run(DSHandleFutureWrapper.java:63) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:636) 2012-03-16 13:29:10,786-0600 INFO ExecutionContext Detailed exception: First operand is null at org.griphyn.vdl.karajan.lib.Operators.vdlop_eq(Operators.java:181) at sun.reflect.GeneratedMethodAccessor7.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:616) at org.globus.cog.karajan.workflow.nodes.functions.FunctionsCollection.function(FunctionsCollection.java:82) at org.globus.cog.karajan.workflow.nodes.functions.AbstractFunction.post(AbstractFunction.java:27) at org.globus.cog.karajan.workflow.nodes.AbstractSequentialWithArguments.futureModified(AbstractSequentialWithArguments.java:208) at org.griphyn.vdl.karajan.DSHandleFutureWrapper$1.run(DSHandleFutureWrapper.java:63) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:636) 2012-03-16 13:29:10,839-0600 INFO Loader Swift finished with errors