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 [  2.25740700e+00   1.64744825e+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  [  4.99973000e-01   5.07391930e-01   1.41117969e+03   2.12605469e+02]
TIMING : Ingest importfits  [  3.49371000e-01   4.56708908e-01   1.41117969e+03   2.13890625e+02]
WARNING : Ingest_AT.py : Adding dummy STOKES-I axis
TIMING : Ingest adddegaxes  [  1.56856000e-01   1.81151152e-01   1.41225781e+03   2.15605469e+02]
TIMING : Ingest summary-0  [  1.41740000e-02   1.43270493e-02   1.41225781e+03   2.15605469e+02]
TIMING : Ingest mask  [  1.94397000e-01   2.11612940e-01   1.41225781e+03   2.15605469e+02]
TIMING : Ingest summary-1  [  1.44430000e-02   1.82659626e-02   1.41225781e+03   2.15605469e+02]
TIMING : Ingest statistics  [  2.54767000e-01   2.55414009e-01   1.41257031e+03   2.16773438e+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  [  7.61367000e-01   7.67365932e-01   1.41557031e+03   2.20121094e+02]
TIMING : Ingest END [ 2.257342    2.42426586]
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 [  4.57523800e+00   1.64744825e+09]
TIMING : CubeStats BEGIN [ 0.  0.]
TIMING : CubeStats imval  [  5.24200000e-02   5.26511669e-02   1.41498047e+03   2.19808594e+02]
TIMING : CubeStats start  [  1.25490000e-02   1.26039982e-02   1.41498047e+03   2.19808594e+02]
TIMING : CubeStats imstat0  [  9.38241000e-01   9.41267967e-01   1.44633203e+03   2.51632812e+02]
TIMING : CubeStats imstat1  [  1.04144300e+00   1.04484797e+00   1.46453906e+03   2.69882812e+02]
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  [  1.33601200e+00   1.34073806e+00   1.46453906e+03   2.69886719e+02]
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  [    2.250128       2.43036199  1474.04296875   277.609375  ]
TIMING : CubeStats done  [  1.35550000e-02   1.35869980e-02   1.47404297e+03   2.77609375e+02]
TIMING : CubeStats summary  [  1.21680000e-02   1.22148991e-02   1.47404297e+03   2.77609375e+02]
TIMING : CubeStats END [ 5.668662    5.86046004]
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.03039330e+01   1.64744825e+09]
TIMING : CubeSum BEGIN [ 0.  0.]
INFO : CubeSum_AT.py : Using constant sigma = 0.112357
TIMING : CubeSum start  [  5.18800000e-02   5.20420074e-02   1.47404297e+03   2.77609375e+02]
TIMING : CubeSum immoments  [  3.21422000e-01   5.64085960e-01   1.47404297e+03   2.77789062e+02]
TIMING : CubeSum statistics  [  2.01690000e-02   2.02469826e-02   1.47404297e+03   2.77789062e+02]
INFO : CubeSum_AT.py : Total flux: 1064763.456746 (sum=35198.781719)
REGRESSION : CSM: [1064763.4567456527, 35198.781718887636]
TIMING : CubeSum implot  [  1.42947000e-01   6.08574319e+00   1.54604688e+03   2.77816406e+02]
TIMING : CubeSum getdata  [  2.26640000e-02   2.27530003e-02   1.54604688e+03   2.77816406e+02]
TIMING : CubeSum done  [  2.36468000e-01   2.56084919e-01   1.54604688e+03   2.77882812e+02]
TIMING : CubeSum END [ 0.809186    7.01462197]
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 [  1.11782660e+01   1.64744826e+09]
TIMING : SFind2D BEGIN [ 0.  0.]
TIMING : SFind2D start  [  3.48690000e-02   3.49769592e-02   1.54604688e+03   2.77882812e+02]
TIMING : SFind2D imstat  [  4.37270000e-02   4.40099239e-02   1.54604688e+03   2.77882812e+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  [  9.55920000e-02   1.38448954e-01   1.54604688e+03   2.78343750e+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  [  1.33138000e-01   1.37109995e-01   1.54604688e+03   2.78343750e+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  [  2.67806000e-01   2.86859035e-01   1.54604688e+03   2.78441406e+02]
TIMING : SFind2D END [ 0.58879     0.65510798]
INFO : AT.py : BDP_OUT[0] = SourceList_BDP x-csm.sl
TIMING : ADMITrun END [  9.919276    16.61198807]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 9.91928 16.612 ]
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 [  1.19792720e+01   1.64744826e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeStats::maxpos,val=[109, 109, 333],8.148375
TIMING : CubeSpectrum CubeStats-pos  [  4.82320000e-02   4.83560562e-02   1.54604688e+03   2.78441406e+02]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[109, 109],206.904739
TIMING : CubeSpectrum Moment-pos  [  4.36230000e-02   4.37550545e-02   1.54604688e+03   2.78441406e+02]
INFO : CubeSpectrum_AT.py : SourceList::maxpos,val=('00h00m00.137s', '-00d00m01.68s'),182.601898
TIMING : CubeSpectrum SourceList-pos  [  3.59520000e-02   3.60720158e-02   1.54604688e+03   2.78441406e+02]
TIMING : CubeSpectrum open  [  1.21460000e-02   1.21810436e-02   1.54604688e+03   2.78441406e+02]
TIMING : CubeSpectrum imval  [  4.43430000e-02   4.45389748e-02   1.54604688e+03   2.78757812e+02]
TIMING : CubeSpectrum imhead  [  4.19290000e-02   4.20768261e-02   1.54604688e+03   2.78757812e+02]
TIMING : CubeSpectrum imval  [  2.24834000e-01   2.43538141e-01   1.54604688e+03   2.78757812e+02]
REGRESSION : CSP: [8.1483745574951172, 8.1483745574951172]
INFO : CubeSpectrum_AT.py : Writing 2 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  2.73072000e-01   2.92023897e-01   1.54604688e+03   2.78800781e+02]
TIMING : CubeSpectrum summary  [  1.23690000e-02   1.24001503e-02   1.54604688e+03   2.78800781e+02]
TIMING : CubeSpectrum END [ 0.748638    0.78711319]
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 [  1.27880590e+01   1.64744826e+09]
TIMING : LineSegment BEGIN [ 0.  0.]
TIMING : LineSegment start  [  2.43360000e-02   2.44009495e-02   1.54604688e+03   2.78800781e+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  [   11.85312       11.88699293  1546.046875     279.19921875]
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  [    3.388933       3.40151715  1546.046875     279.19921875]
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  [    1.855057       1.95884204  1577.5          310.703125  ]
TIMING : LineSegment END [ 17.133746    17.28408694]
INFO : AT.py : BDP_OUT[0] = LineSegment_BDP x.lseg
TIMING : ADMITrun END [ 28.071802    35.01070094]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 28.0718 35.0107 ]
INFO : Admit.py : ADMIT run() called [flowcount 1]
TIMING : ADMITrun END [ 28.172232    35.17354393]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 28.1722 35.1735 ]
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 [  3.02574730e+01   1.64744828e+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  [    6.720041       6.73709607  1577.5          310.76953125]
INFO : LineID_AT.py : Attempting Continuum Subtraction for Input CubeStats Spectra
TIMING : LineID getspectrum-cubestats  [    5.258498       5.27212811  1577.5          310.76953125]
TIMING : LineID segment finder  [  1.38170000e-02   1.38540268e-02   1.57750000e+03   3.10769531e+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  [   15.716899      15.94483781  1665.0390625    399.65625   ]
TIMING : LineID END [ 27.724311    27.98300505]
INFO : AT.py : BDP_OUT[0] = LineList_BDP x.ll
TIMING : ADMITrun END [ 56.220176    63.56930494]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 56.2202 63.5693 ]
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 [  5.82594820e+01   1.64744831e+09]
TIMING : LineCube BEGIN [ 0.  0.]
TIMING : LineCube start  [  2.69946000e-01   2.71029949e-01   1.66503906e+03   3.99781250e+02]
TIMING : LineCube pad  [  1.54190000e-02   1.61268711e-02   1.66503906e+03   3.99781250e+02]
TIMING : LineCube trans-x.CO_115.27120  [  8.96400000e-02   1.97318077e-01   1.66503906e+03   3.99964844e+02]
TIMING : LineCube trans-x.U_115.3778  [  7.95330000e-02   1.19514942e-01   1.66503906e+03   4.00015625e+02]
TIMING : LineCube trans-x.U_115.3876  [  8.13400000e-02   1.51968956e-01   1.66503906e+03   4.00015625e+02]
REGRESSION : LC: [325, 364, 611, 625, 633, 648]
TIMING : LineCube done  [  2.49700000e-02   2.50561237e-02   1.66503906e+03   4.00015625e+02]
TIMING : LineCube END [ 0.573226    0.79342794]
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 [ 57.02155     64.66143703]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 57.0215 64.6614 ]
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 [  5.92633380e+01   1.64744831e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  4.98370000e-02   4.99789715e-02   1.66503906e+03   4.00015625e+02]
TIMING : Moment immoments-0  [  3.46515000e-01   5.13642073e-01   1.66503906e+03   4.00015625e+02]
TIMING : Moment mom0clip  [  7.34920000e-02   1.39857054e-01   1.66503906e+03   4.00148438e+02]
TIMING : Moment implot  [  1.00661000e-01   3.76227856e-01   1.66503906e+03   4.00164062e+02]
TIMING : Moment getdata  [  2.18960000e-02   2.19709873e-02   1.66503906e+03   4.00164062e+02]
TIMING : Moment ren+mask_0  [  3.85772000e-01   3.94150019e-01   1.66353906e+03   3.98664062e+02]
TIMING : Moment done  [  1.56000000e-02   1.56540871e-02   1.66353906e+03   3.98664062e+02]
TIMING : Moment END [ 1.006189    1.52396297]
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 [  6.03305030e+01   1.64744831e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  4.84740000e-02   4.86009121e-02   1.66353906e+03   3.98664062e+02]
TIMING : Moment immoments-all  [  1.06206000e-01   3.32091093e-01   1.66353906e+03   3.98664062e+02]
TIMING : Moment mom0clip  [  7.09690000e-02   1.23168945e-01   1.66353906e+03   3.98664062e+02]
TIMING : Moment implot  [  9.52770000e-02   3.78149033e-01   1.66353906e+03   3.98664062e+02]
TIMING : Moment getdata  [  2.22100000e-02   2.23009586e-02   1.66353906e+03   3.98664062e+02]
TIMING : Moment ren+mask_0  [  2.90211000e-01   2.97056913e-01   1.66303906e+03   3.98164062e+02]
TIMING : Moment makemask  [  1.56928000e-01   6.08479023e-01   1.66303906e+03   3.98632812e+02]
TIMING : Moment implot  [  8.05740000e-02   3.33375931e-01   1.66303906e+03   3.98632812e+02]
TIMING : Moment getdata  [  2.13970000e-02   2.14791298e-02   1.66303906e+03   3.98632812e+02]
TIMING : Moment ren+mask_1  [  3.36227000e-01   3.41114998e-01   1.66303906e+03   3.98820312e+02]
TIMING : Moment makemask  [  1.61626000e-01   4.67622042e-01   1.66303906e+03   3.98820312e+02]
TIMING : Moment implot  [  7.97630000e-02   3.09847832e-01   1.66303906e+03   3.98820312e+02]
TIMING : Moment getdata  [  2.35080000e-02   2.35950947e-02   1.66303906e+03   3.98820312e+02]
TIMING : Moment ren+mask_2  [  3.11163000e-01   3.16370964e-01   1.66303906e+03   3.98820312e+02]
REGRESSION : MOM0FLUX: x.CO_115.27120 42258.8 23755.9 0.0330578 -26.8367 -26.8367 0.383219
TIMING : Moment mom0flux  [  5.44890000e-02   5.46741486e-02   1.66303906e+03   3.98820312e+02]
TIMING : Moment flux-spectrum  [  3.82633000e-01   3.88123989e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment done  [  1.58370000e-02   1.58889294e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment END [ 2.269867    4.09438396]
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 [  6.27091760e+01   1.64744832e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  4.85450000e-02   4.86540794e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment immoments-all  [  9.89340000e-02   2.66180992e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment mom0clip  [  7.14330000e-02   9.57510471e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment implot  [  9.48240000e-02   3.66467953e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment getdata  [  2.28970000e-02   2.29809284e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment ren+mask_0  [  2.96491000e-01   3.01309109e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment makemask  [  1.64597000e-01   6.93106890e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment implot  [  8.08830000e-02   3.19168091e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment getdata  [  2.22330000e-02   2.23169327e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment ren+mask_1  [  2.97312000e-01   3.02321911e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment makemask  [  1.58339000e-01   3.92222166e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment implot  [  7.90600000e-02   3.07395935e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment getdata  [  2.22010000e-02   2.22699642e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment ren+mask_2  [  2.92930000e-01   2.97994137e-01   1.66278906e+03   3.98570312e+02]
REGRESSION : MOM0FLUX: x.U_115.3778 257.505 227.316 0.0330578 -28.6859 -28.6859 1.95065
TIMING : Moment mom0flux  [  5.46790000e-02   5.48729897e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment flux-spectrum  [  3.14423000e-01   3.19715977e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment done  [  1.57970000e-02   1.58398151e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment END [ 2.147886    3.86093998]
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 [  6.49657430e+01   1.64744832e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  4.85010000e-02   4.86190319e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment immoments-all  [  9.74910000e-02   2.20243931e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment mom0clip  [  7.08000000e-02   9.53931808e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment implot  [  9.40550000e-02   3.80939960e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment getdata  [  2.24810000e-02   2.25548744e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment ren+mask_0  [  2.98845000e-01   3.04150105e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment makemask  [  1.58734000e-01   4.02259827e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment implot  [  7.86510000e-02   3.19394112e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment getdata  [  2.22360000e-02   2.23090649e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment ren+mask_1  [  2.89579000e-01   2.94708014e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment makemask  [  1.57347000e-01   4.01474953e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment implot  [  7.80740000e-02   3.07393074e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment getdata  [  2.21580000e-02   2.22349167e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment ren+mask_2  [  2.89306000e-01   2.94543028e-01   1.66278906e+03   3.98570312e+02]
REGRESSION : MOM0FLUX: x.U_115.3876 180.858 210.741 0.0330578 -22.3951 -22.3951 2.49483
TIMING : Moment mom0flux  [  5.41150000e-02   5.42988777e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment flux-spectrum  [  3.09687000e-01   3.14766169e-01   1.66278906e+03   3.98570312e+02]
TIMING : Moment done  [  1.57740000e-02   1.58319473e-02   1.66278906e+03   3.98570312e+02]
TIMING : Moment END [ 2.120304    3.53364706]
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 [  6.71946380e+01   1.64744832e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[109, 109],196.263397
TIMING : CubeSpectrum Moment-pos  [  5.58150000e-02   5.59709072e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum open  [  1.24540000e-02   1.24919415e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum imval  [  3.70920000e-02   3.72831821e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum imhead  [  4.25560000e-02   4.27160263e-02   1.66278906e+03   3.98570312e+02]
REGRESSION : CSP: [8.1483745574951172]
INFO : CubeSpectrum_AT.py : Writing 1 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  2.81312000e-01   2.86253929e-01   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum summary  [  1.24580000e-02   1.24859810e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum END [ 0.454029    0.45958304]
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 [  6.77095830e+01   1.64744832e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[42, 68],7.291157
TIMING : CubeSpectrum Moment-pos  [  5.64120000e-02   5.65779209e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum open  [  1.24040000e-02   1.24411583e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum imval  [  3.67710000e-02   3.69548798e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum imhead  [  4.23110000e-02   4.24771309e-02   1.66278906e+03   3.98570312e+02]
REGRESSION : CSP: [2.3769371509552002]
INFO : CubeSpectrum_AT.py : Writing 1 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  2.71044000e-01   2.75804996e-01   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum summary  [  1.25500000e-02   1.25968456e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum END [ 0.443822    0.44921589]
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 [  6.82139270e+01   1.64744832e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[108, 108],6.795091
TIMING : CubeSpectrum Moment-pos  [  5.58590000e-02   5.60190678e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum open  [  1.23330000e-02   1.23670101e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum imval  [  3.64280000e-02   3.66249084e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum imhead  [  4.21950000e-02   4.23622131e-02   1.66278906e+03   3.98570312e+02]
REGRESSION : CSP: [1.1003997325897217]
INFO : CubeSpectrum_AT.py : Writing 1 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  2.69483000e-01   2.74472952e-01   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum summary  [  1.24920000e-02   1.25808716e-02   1.66278906e+03   3.98570312e+02]
TIMING : CubeSpectrum END [ 0.441144    0.44682002]
INFO : AT.py : BDP_OUT[0] = CubeSpectrum_BDP x.U_115.3876/lc-@3.csp