[Dec 3 12:03:24] DEBUG[24212] chan_dahdi.c: Monitor doohicky got event Ring Begin on channel 8 [Dec 3 12:03:25] DEBUG[24212] chan_dahdi.c: Monitor doohicky got event Ring/Answered on channel 8 [Dec 3 12:03:25] DEBUG[24212] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Dec 3 12:03:25] DEBUG[24212] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Dec 3 12:03:25] DEBUG[24212] devicestate.c: Notification of state change to be queued on device/channel DAHDI/8-1 [Dec 3 12:03:25] DEBUG[24206] devicestate.c: No provider found, checking channel drivers for DAHDI - 8-1 [Dec 3 12:03:25] DEBUG[24206] devicestate.c: Changing state for DAHDI/8-1 - state 0 (Unknown) [Dec 3 12:03:25] DEBUG[24235] app_queue.c: Device 'DAHDI/8-1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Dec 3 12:03:25] VERBOSE[24245] logger.c: -- Starting simple switch on 'DAHDI/8-1' [Dec 3 12:03:26] DEBUG[24245] chan_dahdi.c: CallerID number: 4196333220, name: MANUFACTURED HO, flags=0 [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'Set' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@fax:1] Set("DAHDI/8-1", "FaxLine=1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'Goto' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@fax:2] Goto("DAHDI/8-1", "ivr-main,s,1") in new stack [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Goto (ivr-main,s,1) [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'Answer' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:1] Answer("DAHDI/8-1", "") in new stack [Dec 3 12:03:26] DEBUG[24245] devicestate.c: Notification of state change to be queued on device/channel DAHDI/8 [Dec 3 12:03:26] DEBUG[24245] chan_dahdi.c: Took DAHDI/8-1 off hook [Dec 3 12:03:26] DEBUG[24245] chan_dahdi.c: Enabled echo cancellation on channel 8 [Dec 3 12:03:26] DEBUG[24245] chan_dahdi.c: Engaged echo training on channel 8 [Dec 3 12:03:26] DEBUG[24245] chan_dahdi.c: Requested indication -1 on channel DAHDI/8-1 [Dec 3 12:03:26] DEBUG[24206] devicestate.c: No provider found, checking channel drivers for DAHDI - 8 [Dec 3 12:03:26] DEBUG[24206] devicestate.c: Changing state for DAHDI/8 - state 2 (In use) [Dec 3 12:03:26] DEBUG[24235] app_queue.c: Device 'DAHDI/8' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] pbx.c: Function result is '"MANUFACTURED HO" <4196333220>' [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'NoOp' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:2] NoOp("DAHDI/8-1", "CallerID is ""MANUFACTURED HO" <4196333220>"") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:3] GotoIfTime("DAHDI/8-1", "*,*,1,jan?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:4] GotoIfTime("DAHDI/8-1", "13:00-23:59,*,10,apr?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:5] GotoIfTime("DAHDI/8-1", "*,*,25,may?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:6] GotoIfTime("DAHDI/8-1", "*,*,4,jul?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:7] GotoIfTime("DAHDI/8-1", "*,*,7,sep?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:8] GotoIfTime("DAHDI/8-1", "*,*,26,nov?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:9] GotoIfTime("DAHDI/8-1", "13:00-23:59,*,24,dec?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:10] GotoIfTime("DAHDI/8-1", "*,*,25,dec?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:11] GotoIfTime("DAHDI/8-1", "13:00-23:59,*,31,dec?closed,1") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Not taking any branch [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'GotoIfTime' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [s@ivr-main:12] GotoIfTime("DAHDI/8-1", "08:29-17:31,mon-fri,*,*?open,1") in new stack [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Goto (ivr-main,open,1) [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'Set' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [open@ivr-main:1] Set("DAHDI/8-1", "CHANNEL(musicclass)=default") in new stack [Dec 3 12:03:26] DEBUG[24245] pbx.c: Launching 'Wait' [Dec 3 12:03:26] VERBOSE[24245] logger.c: -- Executing [open@ivr-main:2] Wait("DAHDI/8-1", "1") in new stack [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:26] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=0.000000, Et=0.000000, s/n= nan [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=414775922.000000, Et=4325457920.000000, s/n= 0.11 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45598307584.000000, Et=45623848960.000000, s/n= 1785.27 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45361384832.000000, Et=45394329600.000000, s/n= 1376.89 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45859503232.000000, Et=45910333440.000000, s/n= 902.21 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=1 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45845908096.000000, Et=45882091520.000000, s/n= 1267.04 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=2 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45938675456.000000, Et=45975726080.000000, s/n= 1239.89 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=3 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45771539840.000000, Et=45815096320.000000, s/n= 1050.85 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=4 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45683060096.000000, Et=45712322560.000000, s/n= 1561.15 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=5 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45847134464.000000, Et=45868800000.000000, s/n= 2116.13 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=6 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45468142976.000000, Et=45496422400.000000, s/n= 1607.82 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=7 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45646911616.000000, Et=45676446720.000000, s/n= 1545.51 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=8 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45753503232.000000, Et=45787745280.000000, s/n= 1336.18 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=9 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45696528896.000000, Et=45719900160.000000, s/n= 1955.24 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=10 [Dec 3 12:03:27] DEBUG[24245] pbx.c: Launching 'BackGround' [Dec 3 12:03:27] VERBOSE[24245] logger.c: -- Executing [open@ivr-main:3] BackGround("DAHDI/8-1", "custom/dlink-intro") in new stack [Dec 3 12:03:27] DEBUG[24245] channel.c: Set channel DAHDI/8-1 to write format gsm [Dec 3 12:03:27] DEBUG[24245] channel.c: Scheduling timer at 160 sample intervals [Dec 3 12:03:27] VERBOSE[24245] logger.c: -- Playing 'custom/dlink-intro.gsm' (language 'en') [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45570856832.000000, Et=45601239040.000000, s/n= 1499.92 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=11 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45425060992.000000, Et=45450946560.000000, s/n= 1754.84 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=12 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45807749376.000000, Et=45843456000.000000, s/n= 1282.89 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=13 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45345659136.000000, Et=45391974400.000000, s/n= 979.07 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=14 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45303435392.000000, Et=45344143360.000000, s/n= 1112.89 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=15 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45543017088.000000, Et=45575495680.000000, s/n= 1402.25 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=16 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=45866514176.000000, Et=45912545280.000000, s/n= 996.42 [Dec 3 12:03:27] DEBUG[24245] dsp.c: Hit! count=17 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=1963089176.000000, Et=9626447360.000000, s/n= 0.26 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=300178.000000, Et=12779520.000000, s/n= 0.02 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=378172.000000, Et=17121280.000000, s/n= 0.02 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=114446.000000, Et=13393920.000000, s/n= 0.01 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=834244.000000, Et=15400960.000000, s/n= 0.06 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=487552.000000, Et=13875200.000000, s/n= 0.04 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=586784.000000, Et=15933440.000000, s/n= 0.04 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=2525140.000000, Et=15667200.000000, s/n= 0.19 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=713268.000000, Et=16209920.000000, s/n= 0.05 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=190296.000000, Et=11202560.000000, s/n= 0.02 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=210406.000000, Et=14581760.000000, s/n= 0.01 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=372994.000000, Et=14499840.000000, s/n= 0.03 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=405026.000000, Et=12656640.000000, s/n= 0.03 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=1189058.000000, Et=14796800.000000, s/n= 0.09 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=412660.000000, Et=15841280.000000, s/n= 0.03 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=835132.000000, Et=17981440.000000, s/n= 0.05 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=452424.000000, Et=13762560.000000, s/n= 0.03 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=156246.000000, Et=10659840.000000, s/n= 0.01 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=524048.000000, Et=16506880.000000, s/n= 0.03 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=168930.000000, Et=16209920.000000, s/n= 0.01 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=348368.000000, Et=15523840.000000, s/n= 0.02 [Dec 3 12:03:27] DEBUG[24245] dsp.c: tone 1100, Ew=164770.000000, Et=13045760.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=230312.000000, Et=11909120.000000, s/n= 0.02 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=147528.000000, Et=13168640.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=239844.000000, Et=14356480.000000, s/n= 0.02 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=316724.000000, Et=11438080.000000, s/n= 0.03 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=193370.000000, Et=17100800.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=227082.000000, Et=21483520.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=5482030.000000, Et=56235520.000000, s/n= 0.11 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=41182924.000000, Et=221639680.000000, s/n= 0.23 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=1062374296.000000, Et=8558343680.000000, s/n= 0.14 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=912624502.000000, Et=11954521600.000000, s/n= 0.08 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=35263540.000000, Et=4424051200.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=13144650.000000, Et=1483960320.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=13033314.000000, Et=560995840.000000, s/n= 0.02 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=2541604.000000, Et=706728960.000000, s/n= 0.00 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=417674.000000, Et=84577280.000000, s/n= 0.00 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=869584.000000, Et=17408000.000000, s/n= 0.05 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=37449602.000000, Et=115950080.000000, s/n= 0.48 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=1662072.000000, Et=21637120.000000, s/n= 0.08 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=2508222.000000, Et=24719360.000000, s/n= 0.11 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=10248864.000000, Et=1382151680.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=669610.000000, Et=1357120000.000000, s/n= 0.00 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=2590226.000000, Et=1690959360.000000, s/n= 0.00 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=6679108.000000, Et=2027822080.000000, s/n= 0.00 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=6353572.000000, Et=947054080.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=155300.000000, Et=122411520.000000, s/n= 0.00 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=490902.000000, Et=24709120.000000, s/n= 0.02 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=9673794.000000, Et=28313600.000000, s/n= 0.52 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=4949928.000000, Et=36075520.000000, s/n= 0.16 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=556614806.000000, Et=3314859520.000000, s/n= 0.20 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=25858742.000000, Et=1627494400.000000, s/n= 0.02 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=547844.000000, Et=32755200.000000, s/n= 0.02 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=28729246.000000, Et=87175680.000000, s/n= 0.49 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=20484474.000000, Et=83292160.000000, s/n= 0.33 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=73096360.000000, Et=138012160.000000, s/n= 1.13 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=374267386.000000, Et=861004800.000000, s/n= 0.77 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=1964792800.000000, Et=6909184000.000000, s/n= 0.40 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=40294192.000000, Et=6172165120.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=2050299656.000000, Et=13108467200.000000, s/n= 0.19 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=736659038.000000, Et=8121907200.000000, s/n= 0.10 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=1121825736.000000, Et=2719306240.000000, s/n= 0.70 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=398737400.000000, Et=1746124800.000000, s/n= 0.30 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=90189904.000000, Et=587504640.000000, s/n= 0.18 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=40985378.000000, Et=259555840.000000, s/n= 0.19 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=12672380.000000, Et=290498560.000000, s/n= 0.05 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=2572348.000000, Et=123624960.000000, s/n= 0.02 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=1750022.000000, Et=69143040.000000, s/n= 0.03 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=5604306.000000, Et=158517760.000000, s/n= 0.04 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=62069932.000000, Et=206850560.000000, s/n= 0.43 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=4330104.000000, Et=310366720.000000, s/n= 0.01 [Dec 3 12:03:28] DEBUG[24245] dsp.c: tone 1100, Ew=478599256.000000, Et=1160962560.000000, s/n= 0.70 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=1213439426.000000, Et=2696414720.000000, s/n= 0.82 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=1673572552.000000, Et=4481149440.000000, s/n= 0.60 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=431200954.000000, Et=5018616320.000000, s/n= 0.09 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=2265207464.000000, Et=15217182720.000000, s/n= 0.17 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=16824592928.000000, Et=40144209920.000000, s/n= 0.72 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=2798679600.000000, Et=9378040320.000000, s/n= 0.43 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=614826518.000000, Et=2902942720.000000, s/n= 0.27 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=44908366.000000, Et=327290880.000000, s/n= 0.16 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=588614.000000, Et=11991040.000000, s/n= 0.05 [Dec 3 12:03:29] DEBUG[24242] chan_sip.c: Auto destroying SIP dialog 'b6c953da403f675fef64ac87308313c4@192.168.1.78' [Dec 3 12:03:29] DEBUG[24242] chan_sip.c: Destroying SIP dialog b6c953da403f675fef64ac87308313c4@192.168.1.78 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=107884.000000, Et=12462080.000000, s/n= 0.01 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=192804.000000, Et=12032000.000000, s/n= 0.02 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=172552.000000, Et=14499840.000000, s/n= 0.01 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=4478652.000000, Et=39411200.000000, s/n= 0.13 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=5424168.000000, Et=43653120.000000, s/n= 0.14 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=59300716.000000, Et=241387520.000000, s/n= 0.33 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=37289716.000000, Et=762557440.000000, s/n= 0.05 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=21176168.000000, Et=943234560.000000, s/n= 0.02 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=3339352.000000, Et=1018933760.000000, s/n= 0.00 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=324948.000000, Et=1009216000.000000, s/n= 0.00 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=6487454.000000, Et=527646720.000000, s/n= 0.01 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=9964414.000000, Et=173217280.000000, s/n= 0.06 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=2274274.000000, Et=77680640.000000, s/n= 0.03 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=684878.000000, Et=28006400.000000, s/n= 0.03 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=2278806.000000, Et=15667200.000000, s/n= 0.17 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=654192754.000000, Et=1450447360.000000, s/n= 0.82 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=57339666.000000, Et=622435840.000000, s/n= 0.10 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=82917140.000000, Et=353254400.000000, s/n= 0.31 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=6539372.000000, Et=506119680.000000, s/n= 0.01 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=11690766.000000, Et=387909120.000000, s/n= 0.03 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=8232506.000000, Et=52572160.000000, s/n= 0.19 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=4328100.000000, Et=50741760.000000, s/n= 0.09 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=607210.000000, Et=14817280.000000, s/n= 0.04 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=5907682.000000, Et=64729600.000000, s/n= 0.10 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=7622890.000000, Et=108428800.000000, s/n= 0.08 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=7848236.000000, Et=90526720.000000, s/n= 0.09 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=3012150.000000, Et=65413120.000000, s/n= 0.05 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=30385802.000000, Et=337838080.000000, s/n= 0.10 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=1148804.000000, Et=924912640.000000, s/n= 0.00 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=224572750.000000, Et=2248768000.000000, s/n= 0.11 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=85025250.000000, Et=1163368960.000000, s/n= 0.08 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=294397014.000000, Et=579735040.000000, s/n= 1.03 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=506768634.000000, Et=765844480.000000, s/n= 1.96 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=747356792.000000, Et=1031065600.000000, s/n= 2.63 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=297498258.000000, Et=473292800.000000, s/n= 1.69 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=78483302.000000, Et=160565760.000000, s/n= 0.96 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=2020464.000000, Et=63191040.000000, s/n= 0.03 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=1492438.000000, Et=41269760.000000, s/n= 0.04 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=3180488.000000, Et=34273280.000000, s/n= 0.10 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=259906.000000, Et=18053120.000000, s/n= 0.01 [Dec 3 12:03:29] DEBUG[24245] dsp.c: tone 1100, Ew=2130258.000000, Et=18094080.000000, s/n= 0.13 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=3303674.000000, Et=19046400.000000, s/n= 0.21 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=15402106.000000, Et=40192000.000000, s/n= 0.62 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=5345172.000000, Et=35676160.000000, s/n= 0.18 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=66434114.000000, Et=239751680.000000, s/n= 0.38 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=10672156.000000, Et=228311040.000000, s/n= 0.05 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=3445468.000000, Et=119375360.000000, s/n= 0.03 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=5629052.000000, Et=51317760.000000, s/n= 0.12 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=3049196.000000, Et=30945280.000000, s/n= 0.11 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=2908704.000000, Et=15964160.000000, s/n= 0.22 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=962228.000000, Et=15308800.000000, s/n= 0.07 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=83752.000000, Et=10967040.000000, s/n= 0.01 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=461466.000000, Et=16046080.000000, s/n= 0.03 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=264864.000000, Et=14786560.000000, s/n= 0.02 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=29198234.000000, Et=136716800.000000, s/n= 0.27 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=239857576.000000, Et=796881920.000000, s/n= 0.43 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=714130898.000000, Et=1487669760.000000, s/n= 0.92 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=82930464.000000, Et=745935360.000000, s/n= 0.13 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=3816112.000000, Et=107957760.000000, s/n= 0.04 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=1445486.000000, Et=68446720.000000, s/n= 0.02 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=5364734.000000, Et=37324800.000000, s/n= 0.17 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=3211278.000000, Et=41605120.000000, s/n= 0.08 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=11214826.000000, Et=31283200.000000, s/n= 0.56 [Dec 3 12:03:30] DEBUG[24228] chan_iax2.c: ip callno count decremented to 1 for 72.11.1.226 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=2097828.000000, Et=18022400.000000, s/n= 0.13 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=1136976.000000, Et=14120960.000000, s/n= 0.09 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=112356.000000, Et=13598720.000000, s/n= 0.01 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=3444962.000000, Et=15769600.000000, s/n= 0.28 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=225060.000000, Et=13854720.000000, s/n= 0.02 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=52130.000000, Et=11151360.000000, s/n= 0.00 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=28777277568.000000, Et=36457756160.000000, s/n= 3.75 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45421793408.000000, Et=45461135360.000000, s/n= 1154.54 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45414917120.000000, Et=45448028160.000000, s/n= 1371.59 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45597182464.000000, Et=45635205120.000000, s/n= 1199.21 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=1 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45940252416.000000, Et=45970242560.000000, s/n= 1531.85 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=2 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45938019456.000000, Et=45988362240.000000, s/n= 912.50 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=3 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45089740416.000000, Et=45162424320.000000, s/n= 620.35 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=4 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45463772544.000000, Et=45529866240.000000, s/n= 687.87 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=5 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45148557952.000000, Et=45209036800.000000, s/n= 746.52 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=6 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45712985344.000000, Et=45783260160.000000, s/n= 650.49 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=7 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=44860253696.000000, Et=44920064000.000000, s/n= 750.04 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=8 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45702283904.000000, Et=45748807680.000000, s/n= 982.34 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=9 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45740927488.000000, Et=45787934720.000000, s/n= 973.06 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=10 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45586158848.000000, Et=45614709760.000000, s/n= 1596.66 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=11 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45879618944.000000, Et=45928908800.000000, s/n= 930.81 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=12 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45779654144.000000, Et=45812285440.000000, s/n= 1402.94 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=13 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45707602560.000000, Et=45748899840.000000, s/n= 1106.79 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=14 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45659254784.000000, Et=45685606400.000000, s/n= 1732.69 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=15 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=47111773312.000000, Et=47670932480.000000, s/n= 84.25 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=16 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=53903920768.000000, Et=55240002560.000000, s/n= 40.34 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=17 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=42090072960.000000, Et=42783575040.000000, s/n= 60.69 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=18 [Dec 3 12:03:30] DEBUG[24245] dsp.c: tone 1100, Ew=45064706944.000000, Et=45211115520.000000, s/n= 307.80 [Dec 3 12:03:30] DEBUG[24245] dsp.c: Hit! count=19 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=43177910272.000000, Et=43252090880.000000, s/n= 582.06 [Dec 3 12:03:31] DEBUG[24245] dsp.c: Hit! count=20 [Dec 3 12:03:31] DEBUG[24245] dsp.c: 1100 Hz done detected [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=45538123520.000000, Et=45590174720.000000, s/n= 874.87 [Dec 3 12:03:31] DEBUG[24245] dsp.c: Hit! count=21 [Dec 3 12:03:31] DEBUG[24245] chan_dahdi.c: DTMF digit: f on DAHDI/8-1 [Dec 3 12:03:31] VERBOSE[24245] logger.c: -- Redirecting DAHDI/8-1 to fax extension [Dec 3 12:03:31] DEBUG[24245] channel.c: Soft-Hanging up channel 'DAHDI/8-1' [Dec 3 12:03:31] DEBUG[24245] channel.c: Scheduling timer at 0 sample intervals [Dec 3 12:03:31] DEBUG[24245] channel.c: Set channel DAHDI/8-1 to write format ulaw [Dec 3 12:03:31] VERBOSE[24245] logger.c: -- Sent into invalid extension 'ÿ' in context 'ivr-main' on DAHDI/8-1 [Dec 3 12:03:31] DEBUG[24245] pbx.c: Launching 'BackGround' [Dec 3 12:03:31] VERBOSE[24245] logger.c: -- Executing [i@ivr-main:1] BackGround("DAHDI/8-1", "custom/dlink-invalid") in new stack [Dec 3 12:03:31] DEBUG[24245] channel.c: Set channel DAHDI/8-1 to write format gsm [Dec 3 12:03:31] DEBUG[24245] channel.c: Scheduling timer at 160 sample intervals [Dec 3 12:03:31] VERBOSE[24245] logger.c: -- Playing 'custom/dlink-invalid.gsm' (language 'en') [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=46160088832.000000, Et=46260940800.000000, s/n= 457.70 [Dec 3 12:03:31] DEBUG[24245] dsp.c: Hit! count=22 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=1910661968.000000, Et=9630991360.000000, s/n= 0.25 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=35848.000000, Et=13383680.000000, s/n= 0.00 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=98944.000000, Et=10721280.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=397878.000000, Et=13824000.000000, s/n= 0.03 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=218618.000000, Et=11417600.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=223870.000000, Et=11776000.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=133106.000000, Et=13742080.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=171720.000000, Et=11816960.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=47746.000000, Et=13066240.000000, s/n= 0.00 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=683572.000000, Et=13475840.000000, s/n= 0.05 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=390024.000000, Et=11673600.000000, s/n= 0.03 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=776906.000000, Et=13752320.000000, s/n= 0.06 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=264950.000000, Et=11673600.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=404290.000000, Et=13189120.000000, s/n= 0.03 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=188354.000000, Et=11171840.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=192154.000000, Et=10065920.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=565532.000000, Et=12267520.000000, s/n= 0.05 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=159852.000000, Et=13445120.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=265748.000000, Et=12216320.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=23834.000000, Et=11397120.000000, s/n= 0.00 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=104020.000000, Et=10147840.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=170756.000000, Et=14315520.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=251704.000000, Et=12902400.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=437006.000000, Et=13322240.000000, s/n= 0.03 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=156956.000000, Et=12472320.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=387400.000000, Et=10506240.000000, s/n= 0.04 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=115880.000000, Et=12072960.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=367334.000000, Et=12892160.000000, s/n= 0.03 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=371748.000000, Et=13885440.000000, s/n= 0.03 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=456166.000000, Et=12636160.000000, s/n= 0.04 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=236998.000000, Et=11386880.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=263586.000000, Et=13619200.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=476824.000000, Et=12922880.000000, s/n= 0.04 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=425958.000000, Et=12032000.000000, s/n= 0.04 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=241974.000000, Et=14602240.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=120640.000000, Et=10670080.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24242] chan_sip.c: Auto destroying SIP dialog '63057d6cc0791395' [Dec 3 12:03:31] DEBUG[24242] chan_sip.c: Destroying SIP dialog 63057d6cc0791395 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=234436.000000, Et=12748800.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=21059818.000000, Et=63441920.000000, s/n= 0.50 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=4156712.000000, Et=120005120.000000, s/n= 0.04 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=61458362.000000, Et=488035840.000000, s/n= 0.14 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=2131674.000000, Et=524994560.000000, s/n= 0.00 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=880984.000000, Et=667924480.000000, s/n= 0.00 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=8765444.000000, Et=345479680.000000, s/n= 0.03 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=2207828.000000, Et=134097920.000000, s/n= 0.02 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=10969894.000000, Et=1182824960.000000, s/n= 0.01 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=55624028.000000, Et=660331520.000000, s/n= 0.09 [Dec 3 12:03:31] DEBUG[24245] dsp.c: tone 1100, Ew=1290266.000000, Et=160542720.000000, s/n= 0.01 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=424428.000000, Et=36139520.000000, s/n= 0.01 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=417276.000000, Et=13680640.000000, s/n= 0.03 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=3070972.000000, Et=61475840.000000, s/n= 0.05 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=132490034.000000, Et=239260160.000000, s/n= 1.24 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=5873078.000000, Et=48716800.000000, s/n= 0.14 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=34154832.000000, Et=77035520.000000, s/n= 0.80 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=10175648.000000, Et=38476800.000000, s/n= 0.36 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=2349376.000000, Et=22763520.000000, s/n= 0.12 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=3256806.000000, Et=16158720.000000, s/n= 0.25 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=143410.000000, Et=14581760.000000, s/n= 0.01 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=9491924.000000, Et=163991040.000000, s/n= 0.06 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=256878160.000000, Et=1021278720.000000, s/n= 0.34 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=147240460.000000, Et=1024752640.000000, s/n= 0.17 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=5880866.000000, Et=575923200.000000, s/n= 0.01 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=9801012.000000, Et=192939520.000000, s/n= 0.05 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=750082.000000, Et=13209600.000000, s/n= 0.06 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=190974.000000, Et=1413120.000000, s/n= 0.16 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=11190088.000000, Et=36812800.000000, s/n= 0.44 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=4100552.000000, Et=66859520.000000, s/n= 0.07 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=83453872.000000, Et=190735360.000000, s/n= 0.78 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=1017438.000000, Et=27310080.000000, s/n= 0.04 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=76436.000000, Et=42475520.000000, s/n= 0.00 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=9261732.000000, Et=166927360.000000, s/n= 0.06 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=13946696.000000, Et=689369600.000000, s/n= 0.02 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=23977516.000000, Et=347640320.000000, s/n= 0.07 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=31692382.000000, Et=220999680.000000, s/n= 0.17 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=111854.000000, Et=15534080.000000, s/n= 0.01 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=181188.000000, Et=1187840.000000, s/n= 0.18 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=316486.000000, Et=1454080.000000, s/n= 0.28 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=157356.000000, Et=1566720.000000, s/n= 0.11 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=20242.000000, Et=1259520.000000, s/n= 0.02 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=1263370.000000, Et=4474880.000000, s/n= 0.39 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=123604.000000, Et=2805760.000000, s/n= 0.05 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=129490.000000, Et=1945600.000000, s/n= 0.07 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=10426702.000000, Et=45731840.000000, s/n= 0.30 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=1266218.000000, Et=81364480.000000, s/n= 0.02 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=130112066.000000, Et=217364480.000000, s/n= 1.49 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=88900968.000000, Et=302873600.000000, s/n= 0.42 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=1837016.000000, Et=424350720.000000, s/n= 0.00 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=232768936.000000, Et=1375987200.000000, s/n= 0.20 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=106571168.000000, Et=1349358080.000000, s/n= 0.09 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=77488530.000000, Et=475499520.000000, s/n= 0.19 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=11377756.000000, Et=29276160.000000, s/n= 0.64 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=186406.000000, Et=15319040.000000, s/n= 0.01 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=872342.000000, Et=12226560.000000, s/n= 0.08 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=700578.000000, Et=13649920.000000, s/n= 0.05 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=428364.000000, Et=13045760.000000, s/n= 0.03 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=14307662.000000, Et=56117760.000000, s/n= 0.34 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=39105920.000000, Et=247982080.000000, s/n= 0.19 [Dec 3 12:03:32] DEBUG[24245] dsp.c: tone 1100, Ew=159290950.000000, Et=1101591040.000000, s/n= 0.17 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=2946468392.000000, Et=8063897600.000000, s/n= 0.58 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=20873861888.000000, Et=26277125120.000000, s/n= 3.86 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=4491480128.000000, Et=13749880320.000000, s/n= 0.49 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=148732144.000000, Et=2961443840.000000, s/n= 0.05 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=70751270.000000, Et=694978560.000000, s/n= 0.11 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=1345190.000000, Et=413916160.000000, s/n= 0.00 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=6711232.000000, Et=290910720.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=10857908.000000, Et=284021760.000000, s/n= 0.04 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=1213894.000000, Et=207441920.000000, s/n= 0.01 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=2495362.000000, Et=341483520.000000, s/n= 0.01 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=2775050.000000, Et=330621440.000000, s/n= 0.01 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=5866592.000000, Et=170795520.000000, s/n= 0.04 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=6043416.000000, Et=130951680.000000, s/n= 0.05 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=11138766.000000, Et=137239040.000000, s/n= 0.09 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=310124.000000, Et=12933120.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=495974.000000, Et=20592640.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=3061686.000000, Et=87027200.000000, s/n= 0.04 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=8631946.000000, Et=200261120.000000, s/n= 0.05 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=46767238.000000, Et=185208320.000000, s/n= 0.34 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=17011050.000000, Et=413391360.000000, s/n= 0.04 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=234402.000000, Et=33446400.000000, s/n= 0.01 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=101134.000000, Et=1433600.000000, s/n= 0.08 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=510708.000000, Et=20172800.000000, s/n= 0.03 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=158060.000000, Et=10158080.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=541988.000000, Et=14643200.000000, s/n= 0.04 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=2546896.000000, Et=15861760.000000, s/n= 0.19 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=529932.000000, Et=23664640.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=2455694.000000, Et=58900480.000000, s/n= 0.04 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=7032538.000000, Et=103795200.000000, s/n= 0.07 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=71378860.000000, Et=202117120.000000, s/n= 0.55 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=162435390.000000, Et=305034240.000000, s/n= 1.14 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=142952490.000000, Et=250350080.000000, s/n= 1.33 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=80557150.000000, Et=172802560.000000, s/n= 0.87 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=37501360.000000, Et=83456000.000000, s/n= 0.82 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=206066.000000, Et=3440640.000000, s/n= 0.06 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=79594.000000, Et=921600.000000, s/n= 0.09 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=94306.000000, Et=4782080.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=185924.000000, Et=12206080.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=319686.000000, Et=8632320.000000, s/n= 0.04 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=49797668.000000, Et=153264640.000000, s/n= 0.48 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=87767922.000000, Et=254970880.000000, s/n= 0.52 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=241740.000000, Et=10946560.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=937108.000000, Et=47178240.000000, s/n= 0.02 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=6310186.000000, Et=84451840.000000, s/n= 0.08 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=4147832.000000, Et=66557440.000000, s/n= 0.07 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=1120248.000000, Et=170460160.000000, s/n= 0.01 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=143891954.000000, Et=778327040.000000, s/n= 0.23 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=387375828.000000, Et=952476160.000000, s/n= 0.69 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=295329576.000000, Et=420684800.000000, s/n= 2.36 [Dec 3 12:03:33] DEBUG[24245] dsp.c: tone 1100, Ew=151269796.000000, Et=303244800.000000, s/n= 1.00 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=359518640.000000, Et=520988160.000000, s/n= 2.23 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=425199676.000000, Et=593131520.000000, s/n= 2.53 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=312609394.000000, Et=444136960.000000, s/n= 2.38 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=30161817088.000000, Et=37917222400.000000, s/n= 3.89 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45123629824.000000, Et=45219911680.000000, s/n= 468.66 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45958934400.000000, Et=46034493440.000000, s/n= 608.25 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45888173056.000000, Et=45955522560.000000, s/n= 681.34 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=1 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45947198464.000000, Et=46033909760.000000, s/n= 529.89 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=2 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=43845864320.000000, Et=43914280960.000000, s/n= 640.87 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=3 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45603745792.000000, Et=45658695680.000000, s/n= 829.92 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=4 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45799614720.000000, Et=45840035840.000000, s/n= 1133.06 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=5 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45627059328.000000, Et=45658368000.000000, s/n= 1457.33 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=6 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45784854400.000000, Et=45834240000.000000, s/n= 927.09 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=7 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45711834112.000000, Et=45741670400.000000, s/n= 1532.09 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=8 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45823317504.000000, Et=45867304960.000000, s/n= 1041.74 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=9 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45502483328.000000, Et=45547223040.000000, s/n= 1017.05 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=10 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45845968128.000000, Et=45901557760.000000, s/n= 824.72 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=11 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45896683008.000000, Et=45956157440.000000, s/n= 771.70 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=12 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=46058149504.000000, Et=46094771200.000000, s/n= 1257.67 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=13 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45700735488.000000, Et=45756672000.000000, s/n= 817.01 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=14 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45243569280.000000, Et=45316679680.000000, s/n= 618.84 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=15 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=46000899584.000000, Et=46058562560.000000, s/n= 797.75 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=16 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=46179575808.000000, Et=46232243200.000000, s/n= 876.82 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=17 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45865357824.000000, Et=45915212800.000000, s/n= 919.98 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=18 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45875572992.000000, Et=45918530560.000000, s/n= 1067.93 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=19 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45698299520.000000, Et=45750671360.000000, s/n= 872.57 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=20 [Dec 3 12:03:34] DEBUG[24245] dsp.c: 1100 Hz done detected [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45853376640.000000, Et=45909657600.000000, s/n= 814.72 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=21 [Dec 3 12:03:34] DEBUG[24245] chan_dahdi.c: DTMF digit: f on DAHDI/8-1 [Dec 3 12:03:34] DEBUG[24245] chan_dahdi.c: Fax already handled [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=45903356416.000000, Et=45954416640.000000, s/n= 899.00 [Dec 3 12:03:34] DEBUG[24245] dsp.c: Hit! count=22 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=1940027696.000000, Et=9626081280.000000, s/n= 0.25 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=118736.000000, Et=13301760.000000, s/n= 0.01 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=26322718.000000, Et=62676480.000000, s/n= 0.72 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=37214352.000000, Et=91376640.000000, s/n= 0.69 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=8889894.000000, Et=94300160.000000, s/n= 0.10 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=440148030.000000, Et=1161825280.000000, s/n= 0.61 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=38773946.000000, Et=459816960.000000, s/n= 0.09 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=40648092.000000, Et=322703360.000000, s/n= 0.14 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=72903670.000000, Et=349268480.000000, s/n= 0.26 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=4226454.000000, Et=32501760.000000, s/n= 0.15 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=883048.000000, Et=6318080.000000, s/n= 0.16 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=112374.000000, Et=3624960.000000, s/n= 0.03 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=1420058.000000, Et=14530560.000000, s/n= 0.11 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=175224.000000, Et=11468800.000000, s/n= 0.02 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=1153594.000000, Et=15104000.000000, s/n= 0.08 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=721234.000000, Et=14233600.000000, s/n= 0.05 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=89380.000000, Et=14182400.000000, s/n= 0.01 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=68900.000000, Et=11622400.000000, s/n= 0.01 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=65846012.000000, Et=142868480.000000, s/n= 0.85 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=11607536.000000, Et=498240000.000000, s/n= 0.02 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=446107308.000000, Et=3188915200.000000, s/n= 0.16 [Dec 3 12:03:34] DEBUG[24245] dsp.c: tone 1100, Ew=24983684096.000000, Et=36948426240.000000, s/n= 2.09 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=48915367936.000000, Et=84014653440.000000, s/n= 1.39 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=8896805504.000000, Et=39082416640.000000, s/n= 0.29 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=2374259560.000000, Et=8722065920.000000, s/n= 0.37 [Dec 3 12:03:35] DEBUG[24242] acl.c: Found IP address for this socket [Dec 3 12:03:35] DEBUG[24242] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.1.250:5060 [Dec 3 12:03:35] DEBUG[24242] chan_sip.c: Allocating new SIP dialog for f4b77e86b26da79c624ac28ba4a61e03@192.168.1.77 - REGISTER (No RTP) [Dec 3 12:03:35] DEBUG[24242] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Dec 3 12:03:35] DEBUG[24242] chan_sip.c: Trying to put 'SIP/2.0 40' onto UDP socket destined for 192.168.1.77:5060 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=607821962.000000, Et=2133030400.000000, s/n= 0.40 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=124678980.000000, Et=1506608640.000000, s/n= 0.09 [Dec 3 12:03:35] DEBUG[24242] chan_sip.c: = Found Their Call ID: f4b77e86b26da79c624ac28ba4a61e03@192.168.1.77 Their Tag d7ecb746addc2bf Our tag: as0363644e [Dec 3 12:03:35] DEBUG[24242] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1368921136.000000, Et=2697799680.000000, s/n= 1.03 [Dec 3 12:03:35] VERBOSE[24242] logger.c: > Saved useragent "Aastra 480i/1.4.2.3000 Brcm Callctrl/1.5.1.0 MxSF/v3.2.8.45" for peer 106 [Dec 3 12:03:35] DEBUG[24242] chan_sip.c: Trying to put 'SIP/2.0 20' onto UDP socket destined for 192.168.1.77:5060 [Dec 3 12:03:35] DEBUG[24242] devicestate.c: Notification of state change to be queued on device/channel SIP/106 [Dec 3 12:03:35] DEBUG[24206] devicestate.c: No provider found, checking channel drivers for SIP - 106 [Dec 3 12:03:35] DEBUG[24206] chan_sip.c: Checking device state for peer 106 [Dec 3 12:03:35] DEBUG[24206] devicestate.c: Changing state for SIP/106 - state 1 (Not in use) [Dec 3 12:03:35] DEBUG[24207] devicestate.c: No provider found, checking channel drivers for SIP - 106 [Dec 3 12:03:35] DEBUG[24207] chan_sip.c: Checking device state for peer 106 [Dec 3 12:03:35] DEBUG[24235] app_queue.c: Device 'SIP/106' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=2151680368.000000, Et=3368340480.000000, s/n= 1.77 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=535608466.000000, Et=2816337920.000000, s/n= 0.23 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=94926012.000000, Et=594969600.000000, s/n= 0.19 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=7969420.000000, Et=75189760.000000, s/n= 0.12 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1432858.000000, Et=23534080.000000, s/n= 0.06 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=17320.000000, Et=993280.000000, s/n= 0.02 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=155678.000000, Et=1249280.000000, s/n= 0.14 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=100564.000000, Et=2007040.000000, s/n= 0.05 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=85138.000000, Et=1443840.000000, s/n= 0.06 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=339740.000000, Et=2160640.000000, s/n= 0.19 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=3422724.000000, Et=29693440.000000, s/n= 0.13 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1327780.000000, Et=263554560.000000, s/n= 0.01 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1376748.000000, Et=375132160.000000, s/n= 0.00 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=54499354.000000, Et=275087360.000000, s/n= 0.25 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=3051744.000000, Et=75404800.000000, s/n= 0.04 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=599280.000000, Et=13875200.000000, s/n= 0.05 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=337658.000000, Et=16128000.000000, s/n= 0.02 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1901704.000000, Et=19824640.000000, s/n= 0.11 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=5808146.000000, Et=23050240.000000, s/n= 0.34 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=614732.000000, Et=16527360.000000, s/n= 0.04 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=747224.000000, Et=12789760.000000, s/n= 0.06 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=47284.000000, Et=13752320.000000, s/n= 0.00 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=261686.000000, Et=13199360.000000, s/n= 0.02 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=2176202.000000, Et=18647040.000000, s/n= 0.13 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=37473436.000000, Et=224414720.000000, s/n= 0.20 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=299464506.000000, Et=523069440.000000, s/n= 1.34 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=40383026.000000, Et=134005760.000000, s/n= 0.43 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1157494.000000, Et=4464640.000000, s/n= 0.35 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=2302830.000000, Et=13977600.000000, s/n= 0.20 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1137212.000000, Et=19968000.000000, s/n= 0.06 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=4845178.000000, Et=30876160.000000, s/n= 0.19 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=5268640.000000, Et=54074880.000000, s/n= 0.11 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=603482.000000, Et=30300160.000000, s/n= 0.02 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=1194718.000000, Et=45434880.000000, s/n= 0.03 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=2199822.000000, Et=68431360.000000, s/n= 0.03 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=4571846.000000, Et=56394240.000000, s/n= 0.09 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=9163270.000000, Et=37939200.000000, s/n= 0.32 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=6296360.000000, Et=33976320.000000, s/n= 0.23 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=196644.000000, Et=10332160.000000, s/n= 0.02 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=5678008.000000, Et=38904320.000000, s/n= 0.17 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=4745772.000000, Et=75681280.000000, s/n= 0.07 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=9627698.000000, Et=127685120.000000, s/n= 0.08 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=18241466.000000, Et=115729920.000000, s/n= 0.19 [Dec 3 12:03:35] DEBUG[24245] dsp.c: tone 1100, Ew=5696338.000000, Et=166082560.000000, s/n= 0.04 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=2014636.000000, Et=57774080.000000, s/n= 0.04 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=149908.000000, Et=2160640.000000, s/n= 0.07 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=610494.000000, Et=15308800.000000, s/n= 0.04 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=168174.000000, Et=13742080.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=1576476.000000, Et=25815040.000000, s/n= 0.07 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=57460.000000, Et=3276800.000000, s/n= 0.02 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=389948.000000, Et=33751040.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=14065330.000000, Et=245688320.000000, s/n= 0.06 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=2848468.000000, Et=255964160.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=8767758.000000, Et=250954240.000000, s/n= 0.04 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=19492384.000000, Et=200506880.000000, s/n= 0.11 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=53618422.000000, Et=174663680.000000, s/n= 0.44 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=78899026.000000, Et=271109120.000000, s/n= 0.41 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=16350440.000000, Et=72373760.000000, s/n= 0.29 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=2686470.000000, Et=41420800.000000, s/n= 0.07 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=14674128.000000, Et=51804160.000000, s/n= 0.40 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=7599622.000000, Et=25671680.000000, s/n= 0.42 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=92838.000000, Et=1935360.000000, s/n= 0.05 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=77722.000000, Et=5867520.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=1092984.000000, Et=15626240.000000, s/n= 0.08 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=109174.000000, Et=10741760.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=5792.000000, Et=9830400.000000, s/n= 0.00 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=315410.000000, Et=12001280.000000, s/n= 0.03 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=97826.000000, Et=10127360.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=85470.000000, Et=13905920.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=168152.000000, Et=12615680.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=391676.000000, Et=11120640.000000, s/n= 0.04 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=131640.000000, Et=13148160.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=39986.000000, Et=10987520.000000, s/n= 0.00 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=46458.000000, Et=13271040.000000, s/n= 0.00 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=18664.000000, Et=11898880.000000, s/n= 0.00 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=14324958.000000, Et=78008320.000000, s/n= 0.22 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=262453074.000000, Et=411443200.000000, s/n= 1.76 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=1614690608.000000, Et=3089292800.000000, s/n= 1.10 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=1677695680.000000, Et=2678876160.000000, s/n= 1.68 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=977771206.000000, Et=1227394560.000000, s/n= 3.92 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=50107566.000000, Et=126679040.000000, s/n= 0.65 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=259830.000000, Et=13752320.000000, s/n= 0.02 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=100424.000000, Et=10199040.000000, s/n= 0.01 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=221026.000000, Et=14458880.000000, s/n= 0.02 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=209264.000000, Et=12615680.000000, s/n= 0.02 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=938822.000000, Et=14202880.000000, s/n= 0.07 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=5316482.000000, Et=18237440.000000, s/n= 0.41 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=631488.000000, Et=14264320.000000, s/n= 0.05 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=9025834.000000, Et=140103680.000000, s/n= 0.07 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=883394616.000000, Et=1603793920.000000, s/n= 1.23 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=143314146.000000, Et=915822080.000000, s/n= 0.19 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=37721800.000000, Et=317194240.000000, s/n= 0.13 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=97865792.000000, Et=373429760.000000, s/n= 0.36 [Dec 3 12:03:36] DEBUG[24245] dsp.c: tone 1100, Ew=17829374.000000, Et=109132800.000000, s/n= 0.20 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=4427298.000000, Et=27033600.000000, s/n= 0.20 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=240990.000000, Et=12134400.000000, s/n= 0.02 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=584360.000000, Et=11622400.000000, s/n= 0.05 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=1640860.000000, Et=14161920.000000, s/n= 0.13 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=109632.000000, Et=13957120.000000, s/n= 0.01 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=339828.000000, Et=11601920.000000, s/n= 0.03 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=173574.000000, Et=12021760.000000, s/n= 0.01 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=50440.000000, Et=11786240.000000, s/n= 0.00 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=65620.000000, Et=14714880.000000, s/n= 0.00 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=2554428.000000, Et=43706880.000000, s/n= 0.06 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=236164280.000000, Et=723681280.000000, s/n= 0.48 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=436136350.000000, Et=1171799040.000000, s/n= 0.59 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=331627414.000000, Et=1300341760.000000, s/n= 0.34 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=23456880.000000, Et=423447040.000000, s/n= 0.06 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=6313746.000000, Et=65927680.000000, s/n= 0.11 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=227998.000000, Et=13096960.000000, s/n= 0.02 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=242450.000000, Et=9256960.000000, s/n= 0.03 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=233664.000000, Et=2385920.000000, s/n= 0.11 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=6094582.000000, Et=44986880.000000, s/n= 0.16 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=74938.000000, Et=1495040.000000, s/n= 0.05 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=217752.000000, Et=1208320.000000, s/n= 0.22 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=251826.000000, Et=1443840.000000, s/n= 0.21 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=139592.000000, Et=1075200.000000, s/n= 0.15 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=23140.000000, Et=1054720.000000, s/n= 0.02 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=27976.000000, Et=604160.000000, s/n= 0.05 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=688928.000000, Et=8693760.000000, s/n= 0.09 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=130500.000000, Et=11448320.000000, s/n= 0.01 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=205974.000000, Et=12503040.000000, s/n= 0.02 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=28999039616.000000, Et=36632035840.000000, s/n= 3.80 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=46557749632.000000, Et=46681825280.000000, s/n= 375.24 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=45647979008.000000, Et=45737891840.000000, s/n= 507.69 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=0 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=46181358592.000000, Et=46288199680.000000, s/n= 432.24 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=1 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=45698994816.000000, Et=45766195200.000000, s/n= 680.04 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=2 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=46319268096.000000, Et=46441768960.000000, s/n= 378.11 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=3 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=44169238656.000000, Et=44231336960.000000, s/n= 711.28 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=4 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=43291215872.000000, Et=43361034240.000000, s/n= 620.05 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=5 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=42226052096.000000, Et=42354595840.000000, s/n= 328.50 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=6 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=44528520960.000000, Et=44677038080.000000, s/n= 299.82 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=7 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=46977318272.000000, Et=47090677760.000000, s/n= 414.41 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=8 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=45471946496.000000, Et=45554365440.000000, s/n= 551.72 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=9 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=45813467008.000000, Et=45898931200.000000, s/n= 536.05 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=10 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=45689889792.000000, Et=45728430080.000000, s/n= 1185.51 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=11 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=45118496256.000000, Et=45342888960.000000, s/n= 201.07 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=12 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=45696941824.000000, Et=45904220160.000000, s/n= 220.46 [Dec 3 12:03:37] DEBUG[24245] dsp.c: Hit! count=13 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=335249982.000000, Et=4603637760.000000, s/n= 0.08 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=599242.000000, Et=21995520.000000, s/n= 0.03 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=4182002.000000, Et=85027840.000000, s/n= 0.05 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=125540140.000000, Et=262684160.000000, s/n= 0.92 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=201713668.000000, Et=375503360.000000, s/n= 1.16 [Dec 3 12:03:37] DEBUG[24245] dsp.c: tone 1100, Ew=32866286.000000, Et=116654080.000000, s/n= 0.39 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=57920.000000, Et=7598080.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=168532.000000, Et=1064960.000000, s/n= 0.19 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=99862.000000, Et=2396160.000000, s/n= 0.04 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=601904.000000, Et=12134400.000000, s/n= 0.05 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=870628.000000, Et=12779520.000000, s/n= 0.07 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=2230150.000000, Et=29186560.000000, s/n= 0.08 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=1530202.000000, Et=32235520.000000, s/n= 0.05 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=20749056.000000, Et=89218560.000000, s/n= 0.30 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=3591950.000000, Et=103613440.000000, s/n= 0.04 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=10102164.000000, Et=34675200.000000, s/n= 0.41 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=132984.000000, Et=15953920.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=581940.000000, Et=12523520.000000, s/n= 0.05 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=499116.000000, Et=14172160.000000, s/n= 0.04 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=7733154.000000, Et=43264000.000000, s/n= 0.22 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=5804578.000000, Et=65838080.000000, s/n= 0.10 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=702258.000000, Et=30740480.000000, s/n= 0.02 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=1820321448.000000, Et=178751774720.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=78523008.000000, Et=126594949120.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=9723574.000000, Et=23814520320.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=2129408584.000000, Et=287412039680.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=12186888.000000, Et=16893411840.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=1112136.000000, Et=3739325440.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=2302692.000000, Et=687214080.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=240200.000000, Et=784673280.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=14639000.000000, Et=991915520.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=12376104.000000, Et=614758400.000000, s/n= 0.02 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=2226686.000000, Et=376212480.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=180216.000000, Et=181565440.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=1124562.000000, Et=98593280.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=203080.000000, Et=6236160.000000, s/n= 0.03 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=639392.000000, Et=72990720.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=17912134.000000, Et=131880960.000000, s/n= 0.16 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=29378384.000000, Et=587814400.000000, s/n= 0.05 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=205341412.000000, Et=1109696000.000000, s/n= 0.23 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=18608512.000000, Et=1063157760.000000, s/n= 0.02 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=119301428.000000, Et=769953280.000000, s/n= 0.18 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=146582.000000, Et=234140160.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=7139164.000000, Et=105003520.000000, s/n= 0.07 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=8471044.000000, Et=120081920.000000, s/n= 0.08 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=1087814.000000, Et=129495040.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=370932.000000, Et=131765760.000000, s/n= 0.00 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=2533318.000000, Et=111592960.000000, s/n= 0.02 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=460230.000000, Et=73510400.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=773990.000000, Et=111272960.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=546456.000000, Et=58439680.000000, s/n= 0.01 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=2626164.000000, Et=59571200.000000, s/n= 0.05 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=3004294.000000, Et=77504000.000000, s/n= 0.04 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=38174916.000000, Et=107975680.000000, s/n= 0.55 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=3888456.000000, Et=65392640.000000, s/n= 0.06 [Dec 3 12:03:38] DEBUG[24245] dsp.c: tone 1100, Ew=481586.000000, Et=18595840.000000, s/n= 0.03 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=11930.000000, Et=12359680.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=377504.000000, Et=11857920.000000, s/n= 0.03 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=117096.000000, Et=15298560.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=135444.000000, Et=12083200.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=54944.000000, Et=10731520.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=90194.000000, Et=11560960.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=217056.000000, Et=14909440.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=340988.000000, Et=11386880.000000, s/n= 0.03 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=28660.000000, Et=11622400.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=112810.000000, Et=10301440.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=133526.000000, Et=12226560.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=103404.000000, Et=13772800.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=344672.000000, Et=14561280.000000, s/n= 0.02 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=150124.000000, Et=11735040.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=146060.000000, Et=10291200.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=44900.000000, Et=12503040.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=93466.000000, Et=14325760.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=163546.000000, Et=10741760.000000, s/n= 0.02 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=85254.000000, Et=12738560.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=90600.000000, Et=10536960.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=2626.000000, Et=12779520.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=176874.000000, Et=11294720.000000, s/n= 0.02 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=1994744.000000, Et=24299520.000000, s/n= 0.09 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=2132.000000, Et=22988800.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=170534.000000, Et=12963840.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] channel.c: Scheduling timer at 0 sample intervals [Dec 3 12:03:39] DEBUG[24245] channel.c: Scheduling timer at 0 sample intervals [Dec 3 12:03:39] DEBUG[24245] channel.c: Set channel DAHDI/8-1 to write format ulaw [Dec 3 12:03:39] DEBUG[24245] pbx.c: Launching 'WaitExten' [Dec 3 12:03:39] VERBOSE[24245] logger.c: -- Executing [i@ivr-main:2] WaitExten("DAHDI/8-1", "5") in new stack [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=322554.000000, Et=14182400.000000, s/n= 0.02 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=92356.000000, Et=13762560.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=27560.000000, Et=11366400.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=2050.000000, Et=12277760.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=39604.000000, Et=9533440.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=12932.000000, Et=10055680.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=45032.000000, Et=9011200.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=75588.000000, Et=10926080.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=12148.000000, Et=10178560.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=91054.000000, Et=9605120.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=10018.000000, Et=11192320.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=90004.000000, Et=10014720.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=26900.000000, Et=10352640.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=39730.000000, Et=10608640.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=67572.000000, Et=8867840.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=76714.000000, Et=11079680.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=28370.000000, Et=10106880.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=143186.000000, Et=11417600.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=16904.000000, Et=10311680.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=113800.000000, Et=8519680.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=25562.000000, Et=10536960.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=7072.000000, Et=10086400.000000, s/n= 0.00 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=137246.000000, Et=11601920.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=85378.000000, Et=12636160.000000, s/n= 0.01 [Dec 3 12:03:39] DEBUG[24245] dsp.c: tone 1100, Ew=20176.000000, Et=11601920.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=62660.000000, Et=13250560.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=5266.000000, Et=12503040.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=102024.000000, Et=10782720.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=31076.000000, Et=10598400.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=106380.000000, Et=11540480.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=17666.000000, Et=12472320.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=24980.000000, Et=11407360.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=58708.000000, Et=12513280.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=46042.000000, Et=12154880.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=89484.000000, Et=11345920.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=112374.000000, Et=12267520.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=5476.000000, Et=11806720.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=13312.000000, Et=12584960.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=45140.000000, Et=12318720.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=129976.000000, Et=10250240.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=37888.000000, Et=10444800.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=28786.000000, Et=9502720.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=34312.000000, Et=10782720.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=74740.000000, Et=11909120.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=41522.000000, Et=9728000.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=3764.000000, Et=12298240.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=14866.000000, Et=10178560.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=9010.000000, Et=10229760.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=56898.000000, Et=10158080.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=35540.000000, Et=8755200.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=32036.000000, Et=11417600.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=38660.000000, Et=11929600.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=10600.000000, Et=11724800.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=39114.000000, Et=12380160.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=10016.000000, Et=10291200.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=76840.000000, Et=10833920.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=91588.000000, Et=9738240.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=12146.000000, Et=9809920.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=48500.000000, Et=11284480.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=37330.000000, Et=9963520.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=43444.000000, Et=12636160.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=87774.000000, Et=11386880.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=1268.000000, Et=11079680.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=16354.000000, Et=12001280.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=58384.000000, Et=9574400.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=79300.000000, Et=11991040.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=13428.000000, Et=12544000.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=88750.000000, Et=11345920.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=10832.000000, Et=11386880.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=37258.000000, Et=9226240.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=85358.000000, Et=10516480.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=81380.000000, Et=10291200.000000, s/n= 0.01 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=1088.000000, Et=10106880.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=6548.000000, Et=11427840.000000, s/n= 0.00 [Dec 3 12:03:40] DEBUG[24245] dsp.c: tone 1100, Ew=100056.000000, Et=11110400.000000, s/n= 0.01 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=29978.000000, Et=10772480.000000, s/n= 0.00 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=4744.000000, Et=9697280.000000, s/n= 0.00 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=48154871680.000000, Et=1427758448640.000000, s/n= 0.03 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=29721874.000000, Et=31645672960.000000, s/n= 0.00 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=4010.000000, Et=52293120.000000, s/n= 0.00 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=85800.000000, Et=348160.000000, s/n= 0.33 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=7402.000000, Et=266240.000000, s/n= 0.03 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=468.000000, Et=184320.000000, s/n= 0.00 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=58.000000, Et=174080.000000, s/n= 0.00 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=88062.000000, Et=204800.000000, s/n= 0.75 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=40.000000, Et=163840.000000, s/n= 0.00 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=7048.000000, Et=317440.000000, s/n= 0.02 [Dec 3 12:03:41] DEBUG[24245] dsp.c: tone 1100, Ew=1138.000000, Et=163840.000000, s/n= 0.01 [Dec 3 12:03:41] DEBUG[24242] chan_sip.c: = No match Their Call ID: f4b77e86b26da79c624ac28ba4a61e03@192.168.1.77 Their Tag d7ecb746addc2bf Our tag: as0363644e [Dec 3 12:03:41] DEBUG[24242] acl.c: Found IP address for this socket [Dec 3 12:03:41] DEBUG[24242] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.1.250:5060 [Dec 3 12:03:41] DEBUG[24242] chan_sip.c: Allocating new SIP dialog for 971f12c1fe05491124bc0f4841eb439c@192.168.1.53 - REGISTER (No RTP) [Dec 3 12:03:41] DEBUG[24242] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Dec 3 12:03:41] DEBUG[24242] chan_sip.c: Trying to put 'SIP/2.0 40' onto UDP socket destined for 192.168.1.53:5060 [Dec 3 12:03:41] DEBUG[24245] chan_dahdi.c: Exception on 18, channel 8 [Dec 3 12:03:41] DEBUG[24245] chan_dahdi.c: Got event On hook(1) on channel 8 (index 0) [Dec 3 12:03:41] DEBUG[24245] chan_dahdi.c: Disabled echo cancellation on channel 8 [Dec 3 12:03:41] DEBUG[24245] pbx.c: Spawn extension (ivr-main,i,2) exited non-zero on 'DAHDI/8-1' [Dec 3 12:03:41] VERBOSE[24245] logger.c: == Spawn extension (ivr-main, i, 2) exited non-zero on 'DAHDI/8-1' [Dec 3 12:03:41] DEBUG[24245] channel.c: Soft-Hanging up channel 'DAHDI/8-1' [Dec 3 12:03:41] DEBUG[24245] channel.c: Hanging up channel 'DAHDI/8-1' [Dec 3 12:03:41] DEBUG[24245] chan_dahdi.c: dahdi_hangup(DAHDI/8-1) [Dec 3 12:03:41] DEBUG[24245] chan_dahdi.c: Hangup: channel: 8 index = 0, normal = 18, callwait = -1, thirdcall = -1 [Dec 3 12:03:41] DEBUG[24245] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/8-1 [Dec 3 12:03:41] DEBUG[24245] chan_dahdi.c: Updated conferencing on 8, with 0 conference users [Dec 3 12:03:41] VERBOSE[24245] logger.c: -- Hungup 'DAHDI/8-1'