INFO : AT.py : Setting {'basename': 'x', 'file': 'NGC5194_91112.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 :  NGC5194_91112.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.13516400e+00   1.64394451e+09]
TIMING : Ingest BEGIN [ 0.  0.]
INFO : Ingest_AT.py : OBJECT: NGC5194   SHAPE: [177 177 590]
INFO : Ingest_AT.py : basename=x
INFO : utils.py : OSTYPE: linux
TIMING : Ingest start  [  3.32387000e-01   3.39061975e-01   1.41062109e+03   2.16707031e+02]
TIMING : Ingest importfits  [  2.31448000e-01   2.73768902e-01   1.41095312e+03   2.18300781e+02]
WARNING : Ingest_AT.py : Adding dummy STOKES-I axis
TIMING : Ingest adddegaxes  [  1.19701000e-01   3.08037043e-01   1.41199609e+03   2.19996094e+02]
TIMING : Ingest summary-0  [  1.42010000e-02   1.43101215e-02   1.41199609e+03   2.19996094e+02]
TIMING : Ingest mask  [  1.23549000e-01   1.45020962e-01   1.41199609e+03   2.19996094e+02]
TIMING : Ingest summary-1  [  1.39720000e-02   1.83858871e-02   1.41199609e+03   2.19996094e+02]
TIMING : Ingest statistics  [  1.64920000e-01   1.65343046e-01   1.41237109e+03   2.21484375e+02]
INFO : Ingest_AT.py : COMMONBEAM[3] {'major': {'value': 14.375001600000001, 'unit': 'arcsec'}, 'pa': {'value': 0.0, 'unit': 'deg'}, 'minor': {'value': 14.375001600000001, 'unit': 'arcsec'}}
INFO : Ingest_AT.py : BASICS: [shape] npts min max: [177 177 590   1] 7354350 -0.684751 1.417089
INFO : Ingest_AT.py : S/N (all data): 10.760342
INFO : Ingest_AT.py : GOOD PIXELS: 7354350/18484110 (39.787417% good or 60.212583% bad)
WARNING : Ingest_AT.py : MASKS: ['mask0']
REGRESSION : CUBE: -0.684751 1.41709 0.131696  177 177 590  60.212583
INFO : Ingest_AT.py : TELESCOPE: LMT
INFO : Ingest_AT.py : OBJECT: NGC5194
INFO : Ingest_AT.py : REFFREQTYPE: LSRK
INFO : Ingest_AT.py : RA   Axis 1: 202.469600 -6.250000 88.000000
INFO : Ingest_AT.py : DEC  Axis 2: 47.195170 6.250000 88.000000
INFO : Ingest_AT.py : VLSRv = 0.000000 (from source catalog)
INFO : Ingest_AT.py : VLSRz = 0.000000 +/- 0.000000   1 values: [ 0.]
INFO : Ingest_AT.py : Freq Orig Axis 3: 114.964 0.000390625 0
INFO : Ingest_AT.py : Cube Orig Axis 3: type=Frequency  velocity increment=-1.015921 km/s @ fc=115.078726 fw=0.230469 GHz
INFO : Ingest_AT.py : RESTFREQ: 115.271 115.271 -1
INFO : Ingest_AT.py : VLSRc= 500.589066  VLSRf= 0.000000  VLSRv= 0.000000 VLSRz= 0.000000 WIDTH= -599.393390
INFO : Ingest_AT.py : VLSR = 500.589066 errs = 0.000000 0.000000 0.000000 width = -1.015921
TIMING : Ingest done  [  6.74079000e-01   6.79116964e-01   1.41512109e+03   2.24722656e+02]
TIMING : Ingest END [ 1.686319    1.95513988]
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 [  3.88227700e+00   1.64394451e+09]
TIMING : CubeStats BEGIN [ 0.  0.]
TIMING : CubeStats imval  [  5.17410000e-02   5.19618988e-02   1.41437109e+03   2.24527344e+02]
TIMING : CubeStats start  [  1.24740000e-02   1.25191212e-02   1.41437109e+03   2.24527344e+02]
TIMING : CubeStats imstat0  [  5.94639000e-01   5.96272945e-01   1.44610547e+03   2.56695312e+02]
TIMING : CubeStats imstat1  [  6.28784000e-01   6.30280018e-01   1.41405859e+03   2.25007812e+02]
INFO : CubeStats_AT.py : sigma varies from 0.122973 to 0.139378; 590/590 channels ok
INFO : CubeStats_AT.py : Computing MaxPos for PeakPointPlot
TIMING : CubeStats ppp  [  9.79317000e-01   9.82605934e-01   1.42453516e+03   2.35363281e+02]
INFO : CubeStats_AT.py : CubeMax: 1.417089 @ [ 92  85 297   0]
INFO : CubeStats_AT.py : CubeMin: -0.684751 @ [ 80 112 283   0]
INFO : CubeStats_AT.py : CubeRMS: 0.130048
INFO : CubeStats_AT.py : RMS Sanity check 1.012667
REGRESSION : CST: 0.130048 1.012667
INFO : CubeStats_AT.py : mean,rms,S/N=0.003100 0.130048 10.896642
INFO : CubeStats_AT.py : RMS BAD VARIATION RATIO: 2.048891 2.119324
TIMING : CubeStats plotting  [    2.289417       2.46135998  1454.875        264.01953125]
TIMING : CubeStats done  [  1.37710000e-02   1.40991211e-02   1.45487500e+03   2.64019531e+02]
TIMING : CubeStats summary  [  1.22750000e-02   1.23188496e-02   1.45487500e+03   2.64019531e+02]
TIMING : CubeStats END [ 4.594566    4.77360296]
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 [  8.53717900e+00   1.64394452e+09]
TIMING : CubeSum BEGIN [ 0.  0.]
INFO : CubeSum_AT.py : Using constant sigma = 0.130048
TIMING : CubeSum start  [  5.20380000e-02   5.22029400e-02   1.45487500e+03   2.64023438e+02]
TIMING : CubeSum immoments  [  2.00033000e-01   2.42774010e-01   1.46038281e+03   2.70003906e+02]
TIMING : CubeSum statistics  [  1.99260000e-02   2.00011730e-02   1.46038281e+03   2.70003906e+02]
INFO : CubeSum_AT.py : Total flux: 114793.580727 (sum=2938.716043)
REGRESSION : CSM: [114793.58072698071, 2938.7160427663475]
TIMING : CubeSum implot  [  1.42324000e-01   6.08915997e+00   1.53238672e+03   2.70042969e+02]
TIMING : CubeSum getdata  [  2.16330000e-02   2.17080116e-02   1.53238672e+03   2.70042969e+02]
TIMING : CubeSum done  [  2.35689000e-01   2.54639864e-01   1.53238672e+03   2.70105469e+02]
TIMING : CubeSum END [ 0.684936    6.69383097]
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 [  9.28309300e+00   1.64394453e+09]
TIMING : SFind2D BEGIN [ 0.  0.]
TIMING : SFind2D start  [  3.36590000e-02   3.37650776e-02   1.53238672e+03   2.70105469e+02]
TIMING : SFind2D imstat  [  4.19590000e-02   4.21998501e-02   1.53238672e+03   2.70105469e+02]
INFO : AT.py : Setting 'sigma' = 1.16819084674 for SFind2D_AT
INFO : SFind2D_AT.py : sigma, dmin, dmax, snmax, cutoff 1.16819 -2.36188 48.24 35 0.0968648
WARNING : SFind2D_AT.py : Temporarely patching your K.km/s units to Jy/beam for ia.findsources()
TIMING : SFind2D findsources  [  5.33601000e-01   5.63184977e-01   1.53238672e+03   2.70746094e+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 : 13:29:50.815 +47.11.20.14    91.08    84.41       27.9        235  64.327  21.202  157.7   23.9
INFO : SFind2D_AT.py : 13:29:50.673 +47.11.22.95    91.31    84.86       27.9        225  63.317  19.501  156.0   23.9
INFO : SFind2D_AT.py : 13:29:50.736 +47.11.16.57    91.21    83.83       30.4        167  59.054  15.556  147.9   26.0
INFO : SFind2D_AT.py : 13:29:52.029 +47.12.03.44    89.10    91.33       26.5        118  31.250  31.250  180.0   22.7
INFO : SFind2D_AT.py : 13:29:54.633 +47.10.49.61    84.85    79.52         13       89.1  31.250  31.250    0.0   11.2
INFO : SFind2D_AT.py : 13:29:52.842 +47.11.33.65    87.77    86.57       18.7        580 126.454  54.280  179.8   16.0
INFO : SFind2D_AT.py : 13:29:53.361 +47.11.26.44    86.93    85.41       16.6         55  31.250  31.250   90.0   14.2
INFO : SFind2D_AT.py : 13:29:47.865 +47.12.49.43    95.89    98.69          0        861 159.397  59.968  124.5    0.0
INFO : SFind2D_AT.py : 13:29:53.126 +47.09.28.76    87.31    66.59       5.64       6.18  14.607  10.123  101.5    4.8
INFO : SFind2D_AT.py : 13:29:59.539 +47.13.27.71    76.86   104.82       5.88       45.9  96.193  11.692  154.2    5.0
INFO : SFind2D_AT.py : 13:29:55.314 +47.11.43.51    83.74    88.14        9.9       27.9  31.250  31.250   90.0    8.5
INFO : SFind2D_AT.py : 13:30:00.525 +47.13.06.85    75.25   101.48       5.37       24.4  59.831  11.761  154.3    4.6
INFO : SFind2D_AT.py : Wrote ds9.reg
TIMING : SFind2D table  [  4.08206000e-01   4.13496971e-01   1.53238672e+03   2.70746094e+02]
REGRESSION : CONTFLUX: 12 2435.16
INFO : SFind2D_AT.py :  Fitted Gaussian size; NOT deconvolved source size.
INFO : SFind2D_AT.py :  Restoring Beam: Major axis:       14.4 arcsec , Minor axis:       14.4 arcsec , PA:   0.0 deg
WARNING : SFind2D_AT.py : LogScaling applied
TIMING : SFind2D done  [  3.00416000e-01   3.19519043e-01   1.53238672e+03   2.70871094e+02]
TIMING : SFind2D END [ 1.331325    1.38570786]
INFO : AT.py : BDP_OUT[0] = SourceList_BDP x-csm.sl
TIMING : ADMITrun END [  8.877526    15.44444609]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 8.87753 15.4444 ]
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.08261590e+01   1.64394453e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeStats::maxpos,val=[92, 85, 297],1.417089
TIMING : CubeSpectrum CubeStats-pos  [  4.84780000e-02   4.86059189e-02   1.53238672e+03   2.70871094e+02]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[92, 86],48.240032
TIMING : CubeSpectrum Moment-pos  [  4.30700000e-02   4.31962013e-02   1.53238672e+03   2.70871094e+02]
INFO : CubeSpectrum_AT.py : SourceList::maxpos,val=('13h29m50.815s', '+47d11m20.14s'),27.884987
TIMING : CubeSpectrum SourceList-pos  [  3.63120000e-02   3.64148617e-02   1.53238672e+03   2.70871094e+02]
TIMING : CubeSpectrum open  [  1.22140000e-02   1.22520924e-02   1.53238672e+03   2.70871094e+02]
TIMING : CubeSpectrum imval  [  4.32660000e-02   4.34679985e-02   1.53238672e+03   2.71343750e+02]
TIMING : CubeSpectrum imhead  [  4.16970000e-02   4.18748856e-02   1.53238672e+03   2.71343750e+02]
TIMING : CubeSpectrum imval  [  2.41420000e-01   2.59972095e-01   1.53238672e+03   2.71445312e+02]
TIMING : CubeSpectrum imval  [  2.51536000e-01   2.70448923e-01   1.53238672e+03   2.71445312e+02]
REGRESSION : CSP: [1.4170891046524048, 1.1654877662658691, 1.4139541387557983]
INFO : CubeSpectrum_AT.py : Writing 3 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  2.76536000e-01   2.95746088e-01   1.53238672e+03   2.71445312e+02]
TIMING : CubeSpectrum summary  [  1.22820000e-02   1.23138428e-02   1.53238672e+03   2.71445312e+02]
TIMING : CubeSpectrum END [ 1.019024    1.07653904]
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.19058320e+01   1.64394453e+09]
TIMING : LineSegment BEGIN [ 0.  0.]
TIMING : LineSegment start  [  2.46300000e-02   2.46958733e-02   1.53238672e+03   2.71445312e+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  [   13.559513      13.59949303  1532.38671875   271.86328125]
INFO : LineSegment_AT.py : Detecting segments in CubeSpectrum based data
WARNING : specutil.py : 0 [274, 316]
WARNING : specutil.py : 0 [270, 297]
WARNING : specutil.py : 0 [283, 318]
WARNING : specutil.py : 1 [321, 325]
INFO : LineSegment_AT.py : Detecting segments in CubeStats based data
WARNING : specutil.py : 0 [246, 326]
WARNING : specutil.py : 1 [336, 340]
WARNING : specutil.py : 2 [345, 354]
WARNING : specutil.py : 3 [357, 361]
WARNING : specutil.py : 4 [365, 394]
WARNING : specutil.py : 5 [397, 401]
TIMING : LineSegment segment finder  [    4.09019        4.10507298  1532.38671875   271.86328125]
INFO : LineSegment_AT.py :  Segment Coverage 54 / 590 = 0.0915254
REGRESSION : LINESEG: [[115.05978051423604, 115.09103051635287], [115.09493676661748, 115.09649926672331], [115.09845239185562, 115.10196801709377], [115.10313989217315, 115.10470239227899], [115.10626489238483, 115.11759301815218], [115.11876489323157, 115.1203273933374], [115.07071801497693, 115.08712426608827], [115.0691555148711, 115.07970239058552], [115.07423364021507, 115.08790551614119], [115.08907739122057, 115.09063989132642]]
TIMING : LineSegment done  [    2.308013       2.43636012  1554.4453125    293.89453125]
TIMING : LineSegment END [ 19.994905    20.17821598]
INFO : AT.py : BDP_OUT[0] = LineSegment_BDP x.lseg
TIMING : ADMITrun END [ 30.171065    37.03389001]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 30.1711 37.0339 ]
INFO : Admit.py : ADMIT run() called [flowcount 1]
TIMING : ADMITrun END [ 30.269154   37.1936841]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 30.2692 37.1937 ]
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.22406300e+01   1.64394455e+09]
TIMING : LineID BEGIN [ 0.  0.]
INFO : LineID_AT.py : Set vlsr = 500.59 for line identification.
INFO : LineID_AT.py : Identifylines = True
INFO : LineID_AT.py : Using vlsr = 500.589
INFO : LineID_AT.py : Attempting Continuum Subtraction for Input Spectra
TIMING : LineID getspectrum-cubespecs  [    8.419781       8.44438481  1554.4453125    293.89453125]
INFO : LineID_AT.py : Attempting Continuum Subtraction for Input CubeStats Spectra
TIMING : LineID getspectrum-cubestats  [    5.371693       5.39184618  1554.4453125    293.89453125]
TIMING : LineID segment finder  [  1.41400000e-02   1.42009258e-02   1.55444531e+03   2.93894531e+02]
INFO : LineID_AT.py : Detecting segments in CubeSpectrum based data
WARNING : specutil.py : 0 [274, 316]
WARNING : specutil.py : 0 [270, 297]
WARNING : specutil.py : 0 [283, 318]
WARNING : specutil.py : 1 [321, 325]
INFO : LineID_AT.py : Detecting segments in CubeStats based data
WARNING : specutil.py : 0 [246, 326]
WARNING : specutil.py : 1 [336, 340]
WARNING : specutil.py : 2 [345, 354]
WARNING : specutil.py : 3 [357, 361]
WARNING : specutil.py : 4 [365, 394]
WARNING : specutil.py : 5 [397, 401]
INFO : LineID_AT.py : Searching for spectral peaks with method: PeakFinder
INFO : LineID_AT.py :  Found line: CO 1-0 @ 115.2712GHz, channels 246 - 401
REGRESSION : LINEID: CO 115.27120  246 401
INFO : LineID_AT.py :  Line Coverage 156 / 590 = 0.264407
TIMING : LineID done  [   16.484745      16.70118904  1655.671875     395.0546875 ]
TIMING : LineID END [ 30.304395    30.56569099]
INFO : AT.py : BDP_OUT[0] = LineList_BDP x.ll
TIMING : ADMITrun END [ 60.897181    68.14991307]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 60.8972 68.1499 ]
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 [  6.28231310e+01   1.64394458e+09]
TIMING : LineCube BEGIN [ 0.  0.]
TIMING : LineCube start  [  1.79761000e-01   1.80565119e-01   1.60623438e+03   3.45855469e+02]
TIMING : LineCube pad  [  1.33390000e-02   1.33779049e-02   1.60623438e+03   3.45855469e+02]
TIMING : LineCube trans-x.CO_115.27120  [  9.99460000e-02   2.02642918e-01   1.60623438e+03   3.45976562e+02]
REGRESSION : LC: [241, 406]
TIMING : LineCube done  [  2.51390000e-02   2.52211094e-02   1.60623438e+03   3.45976562e+02]
TIMING : LineCube END [ 0.330553    0.43420815]
INFO : AT.py : BDP_OUT[0] = LineCube_BDP x.CO_115.27120/lc.im
TIMING : ADMITrun END [ 61.398171    68.81527209]
INFO : Admit.py : ADMIT run() finished [flowcount 1] [cpu 61.3982 68.8153 ]
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 - '@1' (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 [  6.34199440e+01   1.64394458e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  4.92240000e-02   4.93409634e-02   1.60623438e+03   3.45976562e+02]
TIMING : Moment immoments-0  [  2.07695000e-01   2.48743057e-01   1.62137109e+03   3.60988281e+02]
TIMING : Moment mom0clip  [  7.03690000e-02   1.37206078e-01   1.62137109e+03   3.61140625e+02]
TIMING : Moment implot  [  9.48470000e-02   3.74497890e-01   1.62137109e+03   3.61140625e+02]
TIMING : Moment getdata  [  2.05910000e-02   2.06651688e-02   1.62137109e+03   3.61140625e+02]
TIMING : Moment ren+mask_0  [  3.14388000e-01   3.19329977e-01   1.62542969e+03   3.65199219e+02]
TIMING : Moment done  [  1.50200000e-02   1.50790215e-02   1.62542969e+03   3.65199219e+02]
TIMING : Moment END [ 0.784616    1.17743897]
INFO : AT.py : BDP_OUT[0] = Moment_BDP x-@1.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.42661310e+01   1.64394458e+09]
TIMING : Moment BEGIN [ 0.  0.]
TIMING : Moment open  [  4.88710000e-02   4.90441322e-02   1.62542969e+03   3.65199219e+02]
TIMING : Moment immoments-all  [  1.29321000e-01   3.33023787e-01   1.62542969e+03   3.65199219e+02]
TIMING : Moment mom0clip  [  6.98660000e-02   1.34177208e-01   1.62542969e+03   3.65199219e+02]
TIMING : Moment implot  [  9.70990000e-02   3.99969816e-01   1.62542969e+03   3.65199219e+02]
TIMING : Moment getdata  [  2.05980000e-02   2.06711292e-02   1.62542969e+03   3.65199219e+02]
TIMING : Moment ren+mask_0  [  2.68574000e-01   2.73024082e-01   1.62542969e+03   3.65199219e+02]
TIMING : Moment makemask  [  1.51949000e-01   4.19026852e-01   1.62542969e+03   3.65957031e+02]
TIMING : Moment implot  [  7.68920000e-02   3.28752995e-01   1.62542969e+03   3.66046875e+02]
TIMING : Moment getdata  [  2.06030000e-02   2.06811428e-02   1.62542969e+03   3.66046875e+02]
TIMING : Moment ren+mask_1  [  2.63507000e-01   2.68143892e-01   1.62542969e+03   3.66046875e+02]
TIMING : Moment makemask  [  1.54860000e-01   4.30037975e-01   1.62542969e+03   3.66046875e+02]
TIMING : Moment implot  [  7.66680000e-02   3.12273026e-01   1.62542969e+03   3.66046875e+02]
TIMING : Moment getdata  [  2.05720000e-02   2.06360817e-02   1.62542969e+03   3.66046875e+02]
TIMING : Moment ren+mask_2  [  2.52310000e-01   2.57129908e-01   1.62542969e+03   3.66046875e+02]
REGRESSION : MOM0FLUX: x.CO_115.27120 11785 6929.67 0.0256 470.363 470.363 46.1756
TIMING : Moment mom0flux  [  5.25690000e-02   5.27279377e-02   1.62542969e+03   3.66046875e+02]
TIMING : Moment flux-spectrum  [  4.32832000e-01   4.37903166e-01   1.62542969e+03   3.66046875e+02]
TIMING : Moment done  [  1.49110000e-02   1.49970055e-02   1.62542969e+03   3.66046875e+02]
TIMING : Moment END [ 2.164359    3.78462696]
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 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.65398710e+01   1.64394459e+09]
TIMING : CubeSpectrum BEGIN [ 0.  0.]
INFO : CubeSpectrum_AT.py : CubeSum::maxpos,val=[92, 86],57.044571
TIMING : CubeSpectrum Moment-pos  [  5.49810000e-02   5.51347733e-02   1.62542969e+03   3.66046875e+02]
TIMING : CubeSpectrum open  [  1.23430000e-02   1.23770237e-02   1.62542969e+03   3.66046875e+02]
TIMING : CubeSpectrum imval  [  3.80920000e-02   3.82740498e-02   1.62542969e+03   3.66046875e+02]
TIMING : CubeSpectrum imhead  [  4.29200000e-02   4.30819988e-02   1.62542969e+03   3.66046875e+02]
REGRESSION : CSP: [1.4139541387557983]
INFO : CubeSpectrum_AT.py : Writing 1 testCubeSpectrum tables
TIMING : CubeSpectrum done  [  2.58631000e-01   2.63437986e-01   1.62542969e+03   3.66046875e+02]
TIMING : CubeSpectrum summary  [  1.25940000e-02   1.26249790e-02   1.62542969e+03   3.66046875e+02]
TIMING : CubeSpectrum END [ 0.431874    0.43727779]
INFO : AT.py : BDP_OUT[0] = CubeSpectrum_BDP x.CO_115.27120/lc-@1.csp