INFO : AT.py : Setting {'basename': 'x', 'file': 'IRC+10216_95125.nf.fits'} for Ingest_AT
INFO : AT.py : Setting 'ppp' = True for CubeStats_AT
INFO : AT.py : Setting 'numsigma' = 4.0 for CubeSum_AT
INFO : AT.py : Setting 'sigma' = 99.0 for CubeSum_AT
INFO : AT.py : Setting 'numsigma' = 4.0 for SFind2D_AT
INFO : Admit.py : ADMIT run() called [flowcount 1]
INFO : 
INFO : 
INFO :    Executing Ingest_AT - '' (V1.2.13)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     box :  []
INFO :     file :  IRC+10216_95125.nf.fits
INFO :     smooth :  []
INFO :     basename :  x
INFO :     mask :  True
INFO :     pb :  
INFO :     edge :  []
INFO :     restfreq :  -1.0
INFO :     vlsr :  -999999.9
INFO :     usepb :  True
INFO : 
TIMING : Ingest ADMIT [  3.27746500e+00   1.64743884e+09]
TIMING : Ingest BEGIN [ 0.  0.]
INFO : Ingest_AT.py : OBJECT: IRC+10216   SHAPE: [219 219 689]
INFO : Ingest_AT.py : basename=x
INFO : utils.py : OSTYPE: linux
TIMING : Ingest start  [  8.60296000e-01   8.68757963e-01   1.19035938e+03   2.05609375e+02]
TIMING : Ingest importfits  [  6.67296000e-01   8.43776941e-01   1.20275000e+03   2.19257812e+02]
WARNING : Ingest_AT.py : Adding dummy STOKES-I axis
TIMING : Ingest adddegaxes  [  3.60892000e-01   4.97467041e-01   1.19162500e+03   2.08882812e+02]
TIMING : Ingest summary-0  [  2.74970000e-02   2.80959606e-02   1.19162500e+03   2.08882812e+02]
TIMING : Ingest mask  [  3.47804000e-01   4.02905941e-01   1.19175391e+03   2.09679688e+02]
TIMING : Ingest summary-1  [  2.68810000e-02   4.90081310e-02   1.19175391e+03   2.09679688e+02]
TIMING : Ingest statistics  [  3.90830000e-01   3.90827894e-01   1.19211328e+03   2.10218750e+02]
INFO : Ingest_AT.py : COMMONBEAM[3] {'major': {'value': 12.6500004, 'unit': 'arcsec'}, 'pa': {'value': 0.0, 'unit': 'deg'}, 'minor': {'value': 12.6500004, 'unit': 'arcsec'}}
INFO : Ingest_AT.py : BASICS: [shape] npts min max: [219 219 689   1] 12129845 -2.475435 8.148375
INFO : Ingest_AT.py : S/N (all data): 61.889010
INFO : Ingest_AT.py : GOOD PIXELS: 12129845/33045129 (36.706908% good or 63.293092% bad)
WARNING : Ingest_AT.py : MASKS: ['mask0']
REGRESSION : CUBE: -2.47543 8.14837 0.131661  219 219 689  63.293092
INFO : Ingest_AT.py : TELESCOPE: LMT
INFO : Ingest_AT.py : OBJECT: IRC+10216
INFO : Ingest_AT.py : REFFREQTYPE: LSRK
INFO : Ingest_AT.py : RA   Axis 1: 0.000000 -5.500001 109.000000
INFO : Ingest_AT.py : DEC  Axis 2: 0.000000 5.500001 109.000000
INFO : Ingest_AT.py : VLSRv = -26.000000 (from source catalog)
INFO : Ingest_AT.py : VLSRz = -25.833333 +/- 0.623610   3 values: [-25.  -26.  -26.5]
INFO : Ingest_AT.py : Freq Orig Axis 3: 115.147 0.000390625 0
INFO : Ingest_AT.py : Cube Orig Axis 3: type=Frequency  velocity increment=-1.015921 km/s @ fc=115.281198 fw=0.269141 GHz
INFO : Ingest_AT.py : RESTFREQ: 115.271 115.271 -1
INFO : Ingest_AT.py : VLSRc= -25.992024  VLSRf= 0.000000  VLSRv= -26.000000 VLSRz= -25.833333 WIDTH= -699.969569
INFO : Ingest_AT.py : VLSR = -26.000000 errs = 0.623610 0.000000 0.000000 width = -1.015921
TIMING : Ingest done  [    1.405595       1.41763711  1194.86328125   213.53515625]
TIMING : Ingest END [ 4.111569    4.52295494]
INFO : AT.py : BDP_OUT[0] = SpwCube_BDP x.im
INFO : 
INFO : 
INFO :    Executing CubeStats_AT - '' (V1.2.3)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     ppp :  True
INFO :     psample :  -1
INFO :     robust :  []
INFO :     maxvrms :  2.0
INFO : 
TIMING : CubeStats ADMIT [  7.51048700e+00   1.64743884e+09]
TIMING : CubeStats BEGIN [ 0.  0.]
TIMING : CubeStats imval  [  1.00301000e-01   1.00302219e-01   1.19387500e+03   2.13171875e+02]
TIMING : CubeStats start  [  2.54130000e-02   2.54108906e-02   1.19387500e+03   2.13171875e+02]
TIMING : CubeStats imstat0  [    2.208491       2.20850706  1225.2265625    245.0078125 ]
TIMING : CubeStats imstat1  [    1.981825       1.98184299  1243.45703125   263.2421875 ]
INFO : CubeStats_AT.py : sigma varies from 0.104370 to 0.146647; 689/689 channels ok
INFO : CubeStats_AT.py : Computing MaxPos for PeakPointPlot
TIMING : CubeStats ppp  [    2.828187       2.82819796  1243.45703125   263.24609375]
INFO : CubeStats_AT.py : CubeMax: 8.148375 @ [109 109 333   0]
INFO : CubeStats_AT.py : CubeMin: -2.475435 @ [ 42  68 470   0]
INFO : CubeStats_AT.py : CubeRMS: 0.112357
INFO : CubeStats_AT.py : RMS Sanity check 1.171807
REGRESSION : CST: 0.112357 1.171807
INFO : CubeStats_AT.py : mean,rms,S/N=0.003884 0.112357 72.521982
INFO : CubeStats_AT.py : RMS BAD VARIATION RATIO: 4.111611 2.179642
TIMING : CubeStats plotting  [    4.267865     215.36088109  1255.78125      271.7890625 ]
TIMING : CubeStats done  [  2.42690000e-02   2.42879391e-02   1.25578125e+03   2.71789062e+02]
TIMING : CubeStats summary  [  2.67190000e-02   2.67179012e-02   1.25578125e+03   2.71789062e+02]
TIMING : CubeStats END [  11.486984   222.5800631]
INFO : AT.py : BDP_OUT[0] = CubeStats_BDP x.cst
INFO : 
INFO : 
INFO :    Executing CubeSum_AT - '' (V1.2.2)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     linesum :  True
INFO :     numsigma :  4.0
INFO :     zoom :  1
INFO :     pad :  5
INFO :     sigma :  99.0
INFO : 
TIMING : CubeSum ADMIT [  1.91207270e+01   1.64743907e+09]
TIMING : CubeSum BEGIN [ 0.  0.]
INFO : CubeSum_AT.py : Using constant sigma = 0.112357
TIMING : CubeSum start  [  1.00262000e-01   1.00261927e-01   1.25578125e+03   2.71789062e+02]
TIMING : CubeSum immoments  [    1.264294       1.48160815  1255.78125      271.7890625 ]
TIMING : CubeSum statistics  [  3.73890000e-02   3.73878479e-02   1.25578125e+03   2.71789062e+02]
INFO : CubeSum_AT.py : Total flux: 1064763.456746 (sum=35198.781719)
REGRESSION : CSM: [1064763.4567456527, 35198.781718887636]
TIMING : CubeSum implot  [  2.61287000e-01   6.32512498e+00   1.32778516e+03   2.72261719e+02]
TIMING : CubeSum getdata  [  3.64750000e-02   3.78019810e-02   1.32778516e+03   2.72261719e+02]
TIMING : CubeSum done  [  4.50830000e-01   2.36960011e+01   1.32778516e+03   2.72265625e+02]
TIMING : CubeSum END [  2.176239    31.70388603]
INFO : AT.py : BDP_OUT[0] = Moment_BDP x.csm
INFO : 
INFO : 
INFO :    Executing SFind2D_AT - 'csm' (V1.2.2)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     nmax :  30
INFO :     numsigma :  4.0
INFO :     snmax :  35.0
INFO :     region :  
INFO :     zoom :  1
INFO :     robust :  ['hin', 1.5]
INFO :     sigma :  -1.0
INFO : 
TIMING : SFind2D ADMIT [  2.14152430e+01   1.64743910e+09]
TIMING : SFind2D BEGIN [ 0.  0.]
TIMING : SFind2D start  [  6.02140000e-02   6.02140427e-02   1.32778516e+03   2.72265625e+02]
TIMING : SFind2D imstat  [  8.21490000e-02   8.21490288e-02   1.32778516e+03   2.72265625e+02]
INFO : AT.py : Setting 'sigma' = 9.18716388648 for SFind2D_AT
INFO : SFind2D_AT.py : sigma, dmin, dmax, snmax, cutoff 9.18716 -10.5606 206.905 35 0.177611
WARNING : SFind2D_AT.py : Temporarely patching your K.km/s units to Jy/beam for ia.findsources()
TIMING : SFind2D findsources  [  1.80910000e-01   2.35989094e-01   1.32778516e+03   2.72265625e+02]
INFO : SFind2D_AT.py : Right Ascen.  Declination   X(pix)   Y(pix)      Peak       Flux    Major   Minor    PA    SNR
INFO : SFind2D_AT.py :                                                K.km/s       Jy    arcsec   arcsec   deg
INFO : SFind2D_AT.py : 00:00:00.137 -00.00.01.68   108.63   108.69        183   1.85e+03  43.125  42.322   72.5   19.9
INFO : SFind2D_AT.py : Wrote ds9.reg
TIMING : SFind2D table  [  2.64719000e-01   2.87036896e-01   1.32778516e+03   2.72265625e+02]
REGRESSION : CONTFLUX: 1 1845.94
INFO : SFind2D_AT.py :  Fitted Gaussian size; NOT deconvolved source size.
INFO : SFind2D_AT.py :  Restoring Beam: Major axis:       12.7 arcsec , Minor axis:       12.7 arcsec , PA:   0.0 deg
TIMING : SFind2D done  [  4.84445000e-01   2.36861210e+01   1.32778516e+03   2.72886719e+02]
TIMING : SFind2D END [  1.09743     24.37649918]
INFO : AT.py : BDP_OUT[0] = SourceList_BDP x-csm.sl
TIMING : ADMITrun END [  19.941709    284.31032205]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 19.9417 284.31 ]
INFO : AT.py : Setting 'csub' = [0, 0] for LineSegment_AT
INFO : Admit.py : ADMIT run() called [flowcount 1]
INFO : 
INFO : 
INFO :    Executing CubeSpectrum_AT - '' (V1.2.5)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     xaxis :  
INFO :     sources :  [0]
INFO :     pos :  []
INFO : 
TIMING : CubeSpectrum ADMIT [  2.29221000e+01   1.64743912e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeStats::maxpos,val=[109, 109, 333],8.148375
TIMING : CubeSpectrum CubeStats-pos  [  9.66550000e-02   9.78188515e-02   1.32778516e+03   2.72886719e+02]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[109, 109],206.904739
TIMING : CubeSpectrum Moment-pos  [  8.35250000e-02   8.45229626e-02   1.32778516e+03   2.72886719e+02]
INFO : CubeSpectrum_AT.py : SourceList::maxpos,val=('00h00m00.137s', '-00d00m01.68s'),182.601898
TIMING : CubeSpectrum SourceList-pos  [  7.57230000e-02   7.57231712e-02   1.32778516e+03   2.72886719e+02]
TIMING : CubeSpectrum open  [  2.78820000e-02   2.78828144e-02   1.32778516e+03   2.72886719e+02]
TIMING : CubeSpectrum imval  [  7.07840000e-02   7.07831383e-02   1.32778516e+03   2.73238281e+02]
TIMING : CubeSpectrum imhead  [  7.84940000e-02   7.85019398e-02   1.32778516e+03   2.73238281e+02]
TIMING : CubeSpectrum imval  [  4.19867000e-01   2.36118870e+01   1.32778516e+03   2.73238281e+02]
REGRESSION : CSP: [8.1483745574951172, 8.1483745574951172]
INFO : CubeSpectrum_AT.py : Writing 2 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  5.27010000e-01   2.37692561e+01   1.32778516e+03   2.73300781e+02]
TIMING : CubeSpectrum summary  [  2.37910000e-02   2.37908363e-02   1.32778516e+03   2.73300781e+02]
TIMING : CubeSpectrum END [  1.426945    47.86338091]
INFO : AT.py : BDP_OUT[0] = CubeSpectrum_BDP x.csp
INFO : 
INFO : 
INFO :    Executing LineSegment_AT - '' (V1.2.3)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     numsigma :  5.0
INFO :     minchan :  4
INFO :     edgechannels :  0
INFO :     smooth :  []
INFO :     recalcnoise :  False
INFO :     maxgap :  3
INFO :     csub :  [0, 0]
INFO :     iterate :  True
INFO :     segment :  ADMIT
INFO : 
TIMING : LineSegment ADMIT [  2.44659290e+01   1.64743917e+09]
TIMING : LineSegment BEGIN [ 0.  0.]
TIMING : LineSegment start  [  4.85600000e-02   4.85599041e-02   1.32778516e+03   2.73300781e+02]
INFO : LineSegment_AT.py : Attempting Continuum Subtraction for Input Spectra
INFO : LineSegment_AT.py : Attempting Continuum Subtraction for Input CubeStats Spectra
TIMING : LineSegment getspectrum  [   24.254906      24.25804901  1327.78515625   273.30078125]
INFO : LineSegment_AT.py : Detecting segments in CubeSpectrum based data
WARNING : specutil.py : 0 [330, 359]
WARNING : specutil.py : 1 [616, 620]
WARNING : specutil.py : 2 [638, 643]
WARNING : specutil.py : 0 [330, 359]
WARNING : specutil.py : 1 [616, 620]
WARNING : specutil.py : 2 [638, 643]
INFO : LineSegment_AT.py : Detecting segments in CubeStats based data
WARNING : specutil.py : 0 [331, 357]
TIMING : LineSegment segment finder  [    6.963804       6.96128702  1327.78515625   273.30078125]
INFO : LineSegment_AT.py :  Segment Coverage 41 / 689 = 0.0595065
REGRESSION : LINESEG: [[115.2761198949221, 115.28627614561007], [115.27572926989565, 115.287057395663], [115.38744802746334, 115.38901052756917], [115.39604177804546, 115.39799490317776], [115.27572926989565, 115.287057395663], [115.38744802746334, 115.38901052756917], [115.39604177804546, 115.39799490317776]]
TIMING : LineSegment done  [    3.509278     119.28186703  1339.1015625    284.9453125 ]
TIMING : LineSegment END [  34.800901    150.57411504]
INFO : AT.py : BDP_OUT[0] = LineSegment_BDP x.lseg
TIMING : ADMITrun END [  56.692754  483.299371]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 56.6928 483.299 ]
INFO : Admit.py : ADMIT run() called [flowcount 1]
TIMING : ADMITrun END [  56.884331    483.51780915]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 56.8843 483.518 ]
INFO : AT.py : Setting 'csub' = [0, 0] for LineID_AT
INFO : AT.py : Setting 'references' = etc/tier1_lines.list for LineID_AT
INFO : Admit.py : ADMIT run() called [flowcount 1]
INFO : 
INFO : 
INFO :    Executing LineID_AT - '' (V1.2.7)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     numsigma :  5.0
INFO :     force :  []
INFO :     tier1width :  0.0
INFO :     online :  False
INFO :     recomblevel :  shallow
INFO :     references :  etc/tier1_lines.list
INFO :     csub :  [0, 0]
INFO :     identifylines :  True
INFO :     iterate :  True
INFO :     segment :  ADMIT
INFO :     allowexotics :  False
INFO :     minchan :  4
INFO :     pattern :  AUTO
INFO :     edgechannels :  0
INFO :     smooth :  []
INFO :     recalcnoise :  False
INFO :     vlsr :  -999999.99
INFO :     maxgap :  3
INFO :     reject :  []
INFO :     method :  {'PeakFinder': {'thresh': 0.0}}
INFO :     mode :  ONE
INFO : 
TIMING : LineID ADMIT [  5.99212550e+01   1.64743932e+09]
TIMING : LineID BEGIN [ 0.  0.]
INFO : LineID_AT.py : Set vlsr = -26.00 for line identification.
INFO : LineID_AT.py : Identifylines = True
INFO : LineID_AT.py : Using vlsr = -26
INFO : LineID_AT.py : Attempting Continuum Subtraction for Input Spectra
TIMING : LineID getspectrum-cubespecs  [   13.806855      13.80800986  1339.1015625    285.15625   ]
INFO : LineID_AT.py : Attempting Continuum Subtraction for Input CubeStats Spectra
TIMING : LineID getspectrum-cubestats  [   10.881991     10.882056   1339.1015625   285.15625  ]
TIMING : LineID segment finder  [  2.66320000e-02   2.66320705e-02   1.33910156e+03   2.85156250e+02]
INFO : LineID_AT.py : Detecting segments in CubeSpectrum based data
WARNING : specutil.py : 0 [330, 359]
WARNING : specutil.py : 1 [616, 620]
WARNING : specutil.py : 2 [638, 643]
WARNING : specutil.py : 0 [330, 359]
WARNING : specutil.py : 1 [616, 620]
WARNING : specutil.py : 2 [638, 643]
INFO : LineID_AT.py : Detecting segments in CubeStats based data
WARNING : specutil.py : 0 [331, 357]
INFO : LineID_AT.py : Searching for spectral peaks with method: PeakFinder
INFO : LineID_AT.py :  Found line: CO 1-0 @ 115.2712GHz, channels 330 - 359
REGRESSION : LINEID: CO 115.27120  330 359
INFO : LineID_AT.py :  Found line: U_115.3778  @ 115.377832318GHz, channels 616 - 620
REGRESSION : LINEID: U_115.3778 115.37783  616 620
INFO : LineID_AT.py :  Found line: U_115.3876  @ 115.387597097GHz, channels 638 - 643
REGRESSION : LINEID: U_115.3876 115.38760  638 643
INFO : LineID_AT.py :  Line Coverage 41 / 689 = 0.0595065
TIMING : LineID done  [   27.806275      36.84171605  1446.11328125   393.27734375]
TIMING : LineID END [ 52.54721     61.58386803]
INFO : AT.py : BDP_OUT[0] = LineList_BDP x.ll
TIMING : ADMITrun END [ 110.050867    545.75183606]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 110.051 545.752 ]
INFO : Admit.py : ADMIT run() called [flowcount 1]
INFO : 
INFO : 
INFO :    Executing LineCube_AT - '' (V1.2.2)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     equalize :  False
INFO :     pad :  5
INFO :     fpad :  -1.0
INFO : 
TIMING : LineCube ADMIT [  1.13007449e+02   1.64743938e+09]
TIMING : LineCube BEGIN [ 0.  0.]
TIMING : LineCube start  [  3.99240000e-01   3.99239063e-01   1.39667578e+03   3.44132812e+02]
TIMING : LineCube pad  [  2.55330000e-02   2.55320072e-02   1.39667578e+03   3.44132812e+02]
TIMING : LineCube trans-x.CO_115.27120  [  1.47003000e-01   4.36213017e-01   1.39667578e+03   3.44257812e+02]
TIMING : LineCube trans-x.U_115.3778  [  1.32138000e-01   4.21426058e-01   1.39667578e+03   3.44378906e+02]
TIMING : LineCube trans-x.U_115.3876  [  1.43898000e-01   4.10827875e-01   1.39667578e+03   3.44378906e+02]
REGRESSION : LC: [325, 364, 611, 625, 633, 648]
TIMING : LineCube done  [  5.93030000e-02   6.02631569e-02   1.39667578e+03   3.44378906e+02]
TIMING : LineCube END [ 0.931124    1.77776814]
INFO : AT.py : BDP_OUT[0] = LineCube_BDP x.CO_115.27120/lc.im
INFO : AT.py : BDP_OUT[1] = LineCube_BDP x.U_115.3778/lc.im
INFO : AT.py : BDP_OUT[2] = LineCube_BDP x.U_115.3876/lc.im
TIMING : ADMITrun END [ 111.436765    548.02094817]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 111.437 548.021 ]
INFO : AT.py : Setting 'moments' = [0, 1, 2] for Moment_AT
INFO : AT.py : Setting 'mom0clip' = 2.0 for Moment_AT
INFO : AT.py : Setting 'moments' = [0, 1, 2] for Moment_AT
INFO : AT.py : Setting 'mom0clip' = 2.0 for Moment_AT
INFO : AT.py : Setting 'moments' = [0, 1, 2] for Moment_AT
INFO : AT.py : Setting 'mom0clip' = 2.0 for Moment_AT
INFO : AT.py : Setting 'numsigma' = [3.0] for Moment_AT
INFO : AT.py : Setting 'mom0clip' = 2.0 for Moment_AT
INFO : Admit.py : ADMIT run() called [flowcount 1]
INFO : 
INFO : 
INFO :    Executing Moment_AT - '@3' (V1.2.2)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     numsigma :  [3.0]
INFO :     moments :  [0]
INFO :     mom0clip :  2.0
INFO :     zoom :  1
INFO :     chans :  
INFO :     sigma :  -1.0
INFO : 
TIMING : Moment ADMIT [  1.14769016e+02   1.64743939e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  1.01260000e-01   1.02676153e-01   1.39667578e+03   3.44378906e+02]
TIMING : Moment immoments-0  [  1.31467100e+00   1.49533200e+00   1.41538281e+03   3.62960938e+02]
TIMING : Moment mom0clip  [  1.14272000e-01   2.73917913e-01   1.41538281e+03   3.63148438e+02]
TIMING : Moment implot  [  1.69414000e-01   6.14301920e-01   1.41538281e+03   3.63207031e+02]
TIMING : Moment getdata  [  3.63550000e-02   3.63559723e-02   1.41538281e+03   3.63207031e+02]
TIMING : Moment ren+mask_0  [  5.33752000e-01   2.44005704e+00   1.41491016e+03   3.62734375e+02]
TIMING : Moment done  [  2.82530000e-02   2.82621384e-02   1.41491016e+03   3.62734375e+02]
TIMING : Moment END [ 2.322132    5.01505804]
INFO : AT.py : BDP_OUT[0] = Moment_BDP x-@3.mom_0
INFO : 
INFO : 
INFO :    Executing Moment_AT - '' (V1.2.2)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     numsigma :  [2.0]
INFO :     moments :  [0, 1, 2]
INFO :     mom0clip :  2.0
INFO :     zoom :  1
INFO :     chans :  
INFO :     sigma :  -1.0
INFO : 
TIMING : Moment ADMIT [  1.17212510e+02   1.64743939e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  9.87290000e-02   9.87298489e-02   1.41491016e+03   3.62734375e+02]
TIMING : Moment immoments-all  [  1.95274000e-01   7.05085039e-01   1.41491016e+03   3.62734375e+02]
TIMING : Moment mom0clip  [  1.16049000e-01   2.79071093e-01   1.41491016e+03   3.62734375e+02]
TIMING : Moment implot  [  1.70233000e-01   5.90230942e-01   1.41491016e+03   3.62734375e+02]
TIMING : Moment getdata  [  3.41540000e-02   3.41539383e-02   1.41491016e+03   3.62734375e+02]
TIMING : Moment ren+mask_0  [  4.43105000e-01   2.45229912e+00   1.41466016e+03   3.62484375e+02]
TIMING : Moment makemask  [  2.28823000e-01   1.11893797e+00   1.41466016e+03   3.62484375e+02]
TIMING : Moment implot  [  1.39130000e-01   5.43463945e-01   1.41466016e+03   3.62484375e+02]
TIMING : Moment getdata  [  3.73680000e-02   3.78699303e-02   1.41466016e+03   3.62484375e+02]
TIMING : Moment ren+mask_1  [  4.34317000e-01   2.22923207e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment makemask  [  2.39251000e-01   1.10586810e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment implot  [  1.38910000e-01   5.11576891e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment getdata  [  3.47760000e-02   3.52199078e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment ren+mask_2  [  4.41768000e-01   2.39579916e+00   1.41466016e+03   3.63457031e+02]
REGRESSION : MOM0FLUX: x.CO_115.27120 42258.8 23755.9 0.0330578 -26.8367 -26.8367 0.383219
TIMING : Moment mom0flux  [  1.14363000e-01   1.14356995e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment flux-spectrum  [  5.43622000e-01   2.40199590e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment done  [  2.56950000e-02   2.56941319e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment END [  3.459418   14.7034359]
INFO : AT.py : BDP_OUT[0] = Moment_BDP x.CO_115.27120/lc.mom_0
INFO : AT.py : BDP_OUT[1] = Moment_BDP x.CO_115.27120/lc.mom_1
INFO : AT.py : BDP_OUT[2] = Moment_BDP x.CO_115.27120/lc.mom_2
INFO : 
INFO : 
INFO :    Executing Moment_AT - '@1' (V1.2.2)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     numsigma :  [2.0]
INFO :     moments :  [0, 1, 2]
INFO :     mom0clip :  2.0
INFO :     zoom :  1
INFO :     chans :  
INFO :     sigma :  -1.0
INFO : 
TIMING : Moment ADMIT [  1.20879621e+02   1.64743941e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  9.63590000e-02   9.63599682e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment immoments-all  [  1.67602000e-01   6.90574169e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment mom0clip  [  1.14192000e-01   2.76198864e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment implot  [  1.70220000e-01   5.94984055e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment getdata  [  3.48370000e-02   3.48370075e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment ren+mask_0  [  4.37496000e-01   2.23141289e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment makemask  [  2.36106000e-01   1.14222002e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment implot  [  1.45470000e-01   5.36995173e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment getdata  [  3.27990000e-02   3.27990055e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment ren+mask_1  [  4.38260000e-01   2.31087899e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment makemask  [  2.31919000e-01   1.11481500e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment implot  [  1.48200000e-01   5.45631886e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment getdata  [  3.28840000e-02   3.28829288e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment ren+mask_2  [  4.37885000e-01   2.29261422e+00   1.41466016e+03   3.63457031e+02]
REGRESSION : MOM0FLUX: x.U_115.3778 257.505 227.316 0.0330578 -28.6859 -28.6859 1.95065
TIMING : Moment mom0flux  [  1.04279000e-01   1.04289770e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment flux-spectrum  [  4.61907000e-01   2.25896001e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment done  [  2.68900000e-02   2.76441574e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment END [  3.347858    14.35472012]
INFO : AT.py : BDP_OUT[0] = Moment_BDP x.U_115.3778/lc-@1.mom_0
INFO : AT.py : BDP_OUT[1] = Moment_BDP x.U_115.3778/lc-@1.mom_1
INFO : AT.py : BDP_OUT[2] = Moment_BDP x.U_115.3778/lc-@1.mom_2
INFO : 
INFO : 
INFO :    Executing Moment_AT - '@2' (V1.2.2)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     numsigma :  [2.0]
INFO :     moments :  [0, 1, 2]
INFO :     mom0clip :  2.0
INFO :     zoom :  1
INFO :     chans :  
INFO :     sigma :  -1.0
INFO : 
TIMING : Moment ADMIT [  1.24470678e+02   1.64743942e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  1.11359000e-01   1.11359119e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment immoments-all  [  1.77005000e-01   6.96869850e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment mom0clip  [  1.12235000e-01   2.69947052e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment implot  [  1.70977000e-01   5.97471952e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment getdata  [  3.44990000e-02   3.45120430e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment ren+mask_0  [  4.52530000e-01   2.32355690e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment makemask  [  2.23100000e-01   1.06839514e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment implot  [  1.32557000e-01   5.12825966e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment getdata  [  3.27020000e-02   3.27029228e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment ren+mask_1  [  4.31490000e-01   2.36291218e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment makemask  [  2.26246000e-01   1.08222485e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment implot  [  1.31079000e-01   4.91070032e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment getdata  [  3.28450000e-02   3.28459740e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment ren+mask_2  [  4.43363000e-01   2.29801106e+00   1.41466016e+03   3.63457031e+02]
REGRESSION : MOM0FLUX: x.U_115.3876 180.858 210.741 0.0330578 -22.3951 -22.3951 2.49483
TIMING : Moment mom0flux  [  1.17212000e-01   1.18043900e-01   1.41466016e+03   3.63457031e+02]
TIMING : Moment flux-spectrum  [  4.82479000e-01   2.27846217e+00   1.41466016e+03   3.63457031e+02]
TIMING : Moment done  [  2.74380000e-02   2.81469822e-02   1.41466016e+03   3.63457031e+02]
TIMING : Moment END [  3.363683    14.36397409]
INFO : AT.py : BDP_OUT[0] = Moment_BDP x.U_115.3876/lc-@2.mom_0
INFO : AT.py : BDP_OUT[1] = Moment_BDP x.U_115.3876/lc-@2.mom_1
INFO : AT.py : BDP_OUT[2] = Moment_BDP x.U_115.3876/lc-@2.mom_2
INFO : 
INFO : 
INFO :    Executing CubeSpectrum_AT - '@1' (V1.2.5)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     xaxis :  
INFO :     sources :  [0]
INFO :     pos :  []
INFO : 
TIMING : CubeSpectrum ADMIT [  1.28042059e+02   1.64743944e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[109, 109],196.263397
TIMING : CubeSpectrum Moment-pos  [  1.04406000e-01   1.04404926e-01   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum open  [  2.41600000e-02   2.41601467e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum imval  [  6.00420000e-02   6.00419044e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum imhead  [  7.79010000e-02   7.79011250e-02   1.41466016e+03   3.63457031e+02]
REGRESSION : CSP: [8.1483745574951172]
INFO : CubeSpectrum_AT.py : Writing 1 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  4.53325000e-01   2.30435300e+00   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum summary  [  2.83880000e-02   2.83868313e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum END [ 0.776422    2.62757516]
INFO : AT.py : BDP_OUT[0] = CubeSpectrum_BDP x.CO_115.27120/lc-@1.csp
INFO : 
INFO : 
INFO :    Executing CubeSpectrum_AT - '@2' (V1.2.5)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     xaxis :  
INFO :     sources :  [0]
INFO :     pos :  []
INFO : 
TIMING : CubeSpectrum ADMIT [  1.28946706e+02   1.64743944e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[42, 68],7.291157
TIMING : CubeSpectrum Moment-pos  [  1.12049000e-01   1.12354994e-01   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum open  [  2.45510000e-02   2.45501995e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum imval  [  5.94620000e-02   5.94639778e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum imhead  [  8.05700000e-02   8.05678368e-02   1.41466016e+03   3.63457031e+02]
REGRESSION : CSP: [2.3769371509552002]
INFO : CubeSpectrum_AT.py : Writing 1 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  4.51918000e-01   2.32280517e+00   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum summary  [  2.31770000e-02   2.31759548e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum END [ 0.779225    2.65041614]
INFO : AT.py : BDP_OUT[0] = CubeSpectrum_BDP x.U_115.3778/lc-@2.csp
INFO : 
INFO : 
INFO :    Executing CubeSpectrum_AT - '@3' (V1.2.5)
INFO : 
INFO : 
INFO :   Run using the following settings:
INFO :     xaxis :  
INFO :     sources :  [0]
INFO :     pos :  []
INFO : 
TIMING : CubeSpectrum ADMIT [  1.29850017e+02   1.64743944e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[108, 108],6.795091
TIMING : CubeSpectrum Moment-pos  [  1.05179000e-01   1.05179071e-01   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum open  [  2.38040000e-02   2.38029957e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum imval  [  6.03450000e-02   6.03458881e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum imhead  [  8.03210000e-02   8.03310871e-02   1.41466016e+03   3.63457031e+02]
REGRESSION : CSP: [1.1003997325897217]
INFO : CubeSpectrum_AT.py : Writing 1 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  4.41697000e-01   2.31314802e+00   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum summary  [  2.68890000e-02   2.68869400e-02   1.41466016e+03   3.63457031e+02]
TIMING : CubeSpectrum END [ 0.765511  2.636971]
INFO : AT.py : BDP_OUT[0] = CubeSpectrum_BDP x.U_115.3876/lc-@3.csp