Call in Hold

Hi Everybody!

I am getting a problem and I dont have any tips… google… nothing to solve… HELP!!

Freepbx 2.1.3
Asterisk 1.2.12.1
Centos 4.4

When I answer a call, if I take it in hold… I cant take this call of hold… the caller stays hearing the MOH… until that I hangup the call. This situation happens with all clients, softphone, hardphone… etc by this reason that I believe the problem is in my Asterisk/Freepbx.

Anyone has “The light”?

Thanks!!!

Oct 25 22:55:06 DEBUG[23023] chan_zap.c: DTMF digit: 3 on Zap/5-1
Oct 25 22:55:06 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:06 DEBUG[23023] pbx.c: Oooh, got something to jump out
with (‘3’)!
Oct 25 22:55:07 DEBUG[23023] chan_zap.c: DTMF digit: 3 on Zap/5-1
Oct 25 22:55:07 DEBUG[23023] chan_zap.c: DTMF digit: 3 on Zap/5-1
Oct 25 22:55:08 DEBUG[23023] chan_zap.c: DTMF digit: 5 on Zap/5-1
Oct 25 22:55:08 VERBOSE[23023] logger.c: == CDR updated on Zap/5-1
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Macro(“Zap/5-
1”, “exten-vm|3335| 3335”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Macro(“Zap/5-
1”, “user-callerid” ) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: _Expression result is '0’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing GotoIf(“Zap/ 5-
1”, “0?report”) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: Not taking any branch
Oct 25 22:55:08 DEBUG[23023] pbx.c: _Expression result is '0’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing GotoIf(“Zap/ 5-
1”, “0?start”) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: Not taking any branch
Oct 25 22:55:08 DEBUG[23023] pbx.c: Function result is ''
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, "REALCALLERIDNUM= ") in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing NoOp(“Zap/5-
1”, "REALCALLERIDNUM is ") in new stack
Oct 25 22:55:08 DEBUG[23023] db.c: Unable to find key ‘/user’ in
family 'DEVICE’
Oct 25 22:55:08 DEBUG[23023] func_db.c: DB: DEVICE//user not found
in database.
Oct 25 22:55:08 DEBUG[23023] pbx.c: Function result is ''
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, “AMPUSER=”) in new stack
Oct 25 22:55:08 DEBUG[23023] db.c: Unable to find key ‘/cidname’ in
family 'AMPUSER’
Oct 25 22:55:08 DEBUG[23023] func_db.c: DB: AMPUSER//cidname not
found in database.
Oct 25 22:55:08 DEBUG[23023] pbx.c: Function result is ''
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, “AMPUSERCIDNAME= “) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: _Expression result is '1’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing GotoIf(“Zap/ 5-
1”, “1?report”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Goto (macro-user-
callerid,s,9)
Oct 25 22:55:08 DEBUG[23023] pbx.c: Function result is '”” <>'
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing NoOp(“Zap/5-
1”, “Using CallerID “” <>”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, “FROMCONTEXT= exten-vm” ) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, “VMBOX=3335” ) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, “EXTTOCALL=3335” ) in new stack
Oct 25 22:55:08 DEBUG[23023] db.c: Unable to find key ‘3335’ in
family 'CFU’
Oct 25 22:55:08 DEBUG[23023] func_db.c: DB: CFU/3335 not found in
database.
Oct 25 22:55:08 DEBUG[23023] pbx.c: Function result is '‘
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, “CFUEXT=”) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: _Expression result is ‘1’
Oct 25 22:55:08 DEBUG[23023] pbx.c: _Expression result is ‘0’
Oct 25 22:55:08 DEBUG[23023] pbx.c: Expression result is ‘1’
Oct 25 22:55:08 DEBUG[23023] pbx.c: Function result is ‘20’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Set(“Zap/5-
1”, “RT=20”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Macro(“Zap/5-
1”, “record-enable| 3335|IN”) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: Function result is ‘0’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing GotoIf(“Zap/ 5-
1”, “0 > 0?2:4”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Goto (macro-record-
enable,s,4)
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing AGI(“Zap/5-
1”, “recordingcheck| 20061025- 225508|116182409 1.1231”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Launched AGI
Script /var/lib/asterisk/ agi-bin/recordin gcheck
Oct 25 22:55:08 VERBOSE[23023] logger.c: recordingcheck| 20061025-
225508|1161824091. 1231: Inbound recording not enabled
Oct 25 22:55:08 VERBOSE[23023] logger.c: – AGI Script
recordingcheck completed, returning 0
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing NoOp(“Zap/5-
1”, “No recording needed”) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: Expression result is '0’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing GotoIf(“Zap/ 5-
1”, “0?dolocaldial| 1”) in new stack
Oct 25 22:55:08 DEBUG[23023] pbx.c: Not taking any branch
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Macro(“Zap/5-
1”, “dial|20|tr| 3335”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing AGI(“Zap/5-
1”, “dialparties. agi”) in new stack
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Launched AGI
Script /var/lib/asterisk/ agi-bin/dialpart ies.agi
Oct 25 22:55:08 VERBOSE[23023] logger.c: dialparties. agi: Starting
New Dialparties. agi
Oct 25 22:55:08 VERBOSE[23023] logger.c: – dialparties. agi:
priority is 1
Oct 25 22:55:08 VERBOSE[23023] logger.c: dialparties. agi: Caller ID
name is ‘unknown’ number is 'unknown’
Oct 25 22:55:08 VERBOSE[23023] logger.c: dialparties. agi:
Methodology of ring is 'none’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – dialparties. agi: Added
extension 3335 to extension map
Oct 25 22:55:08 DEBUG[23023] db.c: Unable to find key ‘3335’ in
family 'CF’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – dialparties. agi:
Extension 3335 cf is disabled
Oct 25 22:55:08 DEBUG[23023] db.c: Unable to find key ‘3335’ in
family 'DND’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – dialparties. agi:
Extension 3335 do not disturb is disabled
Oct 25 22:55:08 DEBUG[23023] db.c: Unable to find key ‘3335’ in
family 'CFB’
Oct 25 22:55:08 DEBUG[23023] db.c: Unable to find key ‘3335’ in
family 'CFU’
Oct 25 22:55:08 VERBOSE[23023] logger.c: – dialparties. agi: DbSet
CALLTRACE/3335 to unknown
Oct 25 22:55:08 VERBOSE[23023] logger.c: – AGI Script
dialparties. agi completed, returning 0
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Executing Dial(“Zap/5-
1”, “SIP/3335|20| tr”) in new stack
Oct 25 22:55:08 DEBUG[23023] chan_sip.c: Setting NAT on RTP to 0
Oct 25 22:55:08 DEBUG[23023] chan_sip.c: Setting NAT on VRTP to 0
Oct 25 22:55:08 DEBUG[23023] chan_sip.c: Outgoing Call for 3335
Oct 25 22:55:08 VERBOSE[23023] logger.c: – Called 3335
Oct 25 22:55:08 DEBUG[23023] chan_zap.c: Requested indication 3 on
channel Zap/5-1
Oct 25 22:55:08 DEBUG[15251] chan_sip.c: (Provisional) Stopping
retransmission (but retaining packet)
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ Request 102:
Found
Oct 25 22:55:08 VERBOSE[23023] logger.c: – SIP/3335-09834470 is
ringing
Oct 25 22:55:11 DEBUG[15251] chan_sip.c: Acked pending invite 102
Oct 25 22:55:11 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Request 102:
Match Found
Oct 25 22:55:11 DEBUG[15251] chan_sip.c: build_route: Contact hop:
Oct 25 22:55:11 VERBOSE[23023] logger.c: – SIP/3335-09834470
answered Zap/5-1
Oct 25 22:55:11 DEBUG[23023] chan_zap.c: Requested indication -1 on
channel Zap/5-1
Oct 25 22:55:11 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:11 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:11 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:11 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:11 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:11 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 2:
Match Found
Oct 25 22:55:11 NOTICE[23023] rtp.c: Unknown RTP codec 126 received
Oct 25 22:55:11 NOTICE[23023] rtp.c: Unknown RTP codec 126 received
Oct 25 22:55:11 NOTICE[23023] rtp.c: Unknown RTP codec 126 received
Oct 25 22:55:17 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:55:17 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:17 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:17 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:17 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:17 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:17 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:17 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:17 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:17 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:17 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:17 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 2:
Match Found
Oct 25 22:55:17 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 3:
Match Found
Oct 25 22:55:19 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:19 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:19 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:19 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:19 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:19 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:19 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 4:
Match Found
Oct 25 22:55:21 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:21 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:21 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:21 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:21 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:21 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:22 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 5:
Match Found
Oct 25 22:55:23 NOTICE[23015] rtp.c: Unknown RTP codec 126 received
Oct 25 22:55:26 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:26 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:26 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:26 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:26 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:26 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:26 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 6:
Match Found
Oct 25 22:55:27 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:55:27 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:55:27 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:27 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:27 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:27 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:28 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 3:
Match Found
Oct 25 22:55:30 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:55:30 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:55:30 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:30 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:30 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:30 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:30 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:30 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:30 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:30 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:30 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:30 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:30 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 4:
Match Found
Oct 25 22:55:30 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 7:
Match Found
Oct 25 22:55:33 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:33 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:33 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:33 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:33 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:33 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:33 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 8:
Match Found
Oct 25 22:55:34 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:55:34 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:55:34 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:34 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:34 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:34 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:34 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 5:
Match Found
Oct 25 22:55:40 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:55:40 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:55:40 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:40 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:40 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:40 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:40 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:40 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:40 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:40 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:40 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 6:
Match Found
Oct 25 22:55:40 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 9:
Match Found
Oct 25 22:55:40 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:40 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:44 NOTICE[23015] rtp.c: Unknown RTP codec 126 received
Oct 25 22:55:46 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:46 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:46 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:46 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:46 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:46 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:47 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 10:
Match Found
Oct 25 22:55:48 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:48 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:48 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:48 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:48 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:48 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:48 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 11:
Match Found
Oct 25 22:55:50 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:50 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:50 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:50 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:50 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:50 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:50 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:55:50 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:55:50 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:50 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:50 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:50 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:50 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 12:
Match Found
Oct 25 22:55:50 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 7:
Match Found
Oct 25 22:55:52 NOTICE[23023] rtp.c: Unknown RTP codec 126 received
Oct 25 22:55:54 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:55:54 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:55:54 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:54 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:54 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:54 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:54 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 8:
Match Found
Oct 25 22:55:54 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:55:54 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/4-1
Oct 25 22:55:54 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:55:54 DEBUG[23015] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:55:54 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:55:54 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:55:55 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Response 13:
Match Found
Oct 25 22:56:05 NOTICE[23015] rtp.c: Unknown RTP codec 126 received
Oct 25 22:56:06 WARNING[23015] interface.c: Junk at the beginning of
frame 54414730
Oct 25 22:56:06 WARNING[23015] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:56:08 ERROR[15251] chan_sip.c: Got SUBSCRIBE for extension
[email protected] internal from 192.168.236. 49, but there is no hint
for that extension
Oct 25 22:56:12 WARNING[23023] interface.c: Junk at the beginning of
frame 54414730
Oct 25 22:56:12 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:56:14 DEBUG[15296] manager.c: Manager received
command 'Command’
Oct 25 22:56:14 DEBUG[15296] manager.c: Manager received
command 'Command’
Oct 25 22:56:15 NOTICE[23015] rtp.c: Unknown RTP codec 126 received
Oct 25 22:56:25 NOTICE[23015] rtp.c: Unknown RTP codec 126 received
Oct 25 22:56:36 NOTICE[23015] rtp.c: Unknown RTP codec 126 received
Oct 25 22:56:46 NOTICE[23015] rtp.c: Unknown RTP codec 126 received
Oct 25 22:56:52 DEBUG[23015] dsp.c: ast_dsp_busydetect detected
busy, avgtone: 334, avgsilence 311
Oct 25 22:56:52 DEBUG[23015] dsp.c: Requesting Hangup because the
busy tone was detected on channel Zap/4-1
Oct 25 22:56:52 VERBOSE[23015] logger.c: – Stopped music on hold on
Zap/4-1
Oct 25 22:56:52 DEBUG[23015] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:56:52 DEBUG[23015] channel.c: Didn’t get a frame from
channel: Zap/4-1
Oct 25 22:56:52 DEBUG[23015] channel.c: Bridge stops bridging
channels Zap/4-1 and SIP/3335-098041a0
Oct 25 22:56:52 DEBUG[23015] chan_sip.c: update_call
counter(3335) -
decrement call limit counter
Oct 25 22:56:52 DEBUG[23015] app_dial.c: Exiting with
DIALSTATUS=ANSWER.
Oct 25 22:56:52 VERBOSE[23015] logger.c: == Spawn extension (macro-
dial, s, 10) exited non-zero on ‘Zap/4-1’ in macro ‘dial’
Oct 25 22:56:52 VERBOSE[23015] logger.c: == Spawn extension (macro-
dial, s, 10) exited non-zero on ‘Zap/4-1’ in macro ‘exten-vm’
Oct 25 22:56:52 VERBOSE[23015] logger.c: == Spawn extension (macro-
dial, s, 10) exited non-zero on ‘Zap/4-1’
Oct 25 22:56:52 DEBUG[23015] cdr_addon_mysql. c: cdr_mysql: inserting
a CDR record.
Oct 25 22:56:52 DEBUG[23015] cdr_addon_mysql. c: cdr_mysql: SQL
command as follows: INSERT INTO cdr
(calldate,clid, src,dst,dcontext ,channel, dstchannel, lastapp,lastdata ,d
uration,billsec, disposition, amaflags, accountcode, uniqueid) VALUES
(‘2006-10-25 22:54:03’,’’ ,’’,‘3335’ ,‘ivr-8’, ‘Zap/4-1’,‘SIP/ 3335-
098041a0’,‘Dial’ ,‘SIP/3335| 20|tr’,169, 169,‘ANSWERED’ ,3,’’,‘116182403 8
.1229’)
Oct 25 22:56:52 DEBUG[23015] chan_zap.c: Hangup: channel: 4 index =
0, normal = 18, callwait = -1, thirdcall = -1
Oct 25 22:56:52 DEBUG[23015] chan_zap.c: disabled echo cancellation
on channel 4
Oct 25 22:56:52 DEBUG[23015] chan_zap.c: Set option TDD MODE, value:
OFF(0) on Zap/4-1
Oct 25 22:56:52 DEBUG[23015] chan_zap.c: Updated conferencing on 4,
with 0 conference users
Oct 25 22:56:52 VERBOSE[23015] logger.c: – Hungup 'Zap/4-1’
Oct 25 22:56:52 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘1e3858fb16bf72e666 [email protected] 192.168.236. 12’ of Request 103:
Match Found
Oct 25 22:56:54 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:56:54 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:56:54 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:56:54 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:56:54 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:56:54 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:56:54 NOTICE[23023] rtp.c: Unknown RTP codec 126 received
Oct 25 22:56:54 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 9:
Match Found
Oct 25 22:57:01 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:57:01 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:57:01 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:57:01 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:57:01 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:57:01 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:57:01 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 10:
Match Found
Oct 25 22:57:03 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:57:03 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:57:03 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:57:03 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:57:03 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:57:03 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:57:03 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 11:
Match Found
Oct 25 22:57:03 WARNING[23023] interface.c: Junk at the beginning of
frame 54414731
Oct 25 22:57:03 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:57:04 NOTICE[23023] rtp.c: Unknown RTP codec 126 received
Oct 25 22:57:06 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:57:06 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:57:06 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:57:06 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:57:06 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:57:06 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:57:06 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 12:
Match Found
Oct 25 22:57:07 VERBOSE[15251] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:57:07 VERBOSE[15251] logger.c: – Started music on hold,
class ‘default’, on Zap/5-1
Oct 25 22:57:07 DEBUG[15251] channel.c: Scheduling timer at 160
sample intervals
Oct 25 22:57:07 DEBUG[23023] channel.c: Generator got voice,
switching to phase locked mode
Oct 25 22:57:07 DEBUG[23023] channel.c: Scheduling timer at 0 sample
intervals
Oct 25 22:57:07 WARNING[23023] interface.c: Junk at the beginning of
frame 49443303
Oct 25 22:57:07 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘4f357a013fc095cb3c [email protected] 192.168.236. 12’ of Response 13:
Match Found
Oct 25 22:57:12 DEBUG[23023] channel.c: Didn’t get a frame from
channel: SIP/3335-09834470
Oct 25 22:57:12 DEBUG[23023] channel.c: Bridge stops bridging
channels Zap/5-1 and SIP/3335-09834470
Oct 25 22:57:12 DEBUG[23023] chan_sip.c: update_call
counter(3335) -
decrement call limit counter
Oct 25 22:57:12 DEBUG[23023] app_dial.c: Exiting with
DIALSTATUS=ANSWER.
Oct 25 22:57:12 VERBOSE[23023] logger.c: == Spawn extension (macro-
dial, s, 10) exited non-zero on ‘Zap/5-1’ in macro ‘dial’
Oct 25 22:57:12 VERBOSE[23023] logger.c: == Spawn extension (macro-
dial, s, 10) exited non-zero on ‘Zap/5-1’ in macro ‘exten-vm’
Oct 25 22:57:12 VERBOSE[23023] logger.c: == Spawn extension (macro-
dial, s, 10) exited non-zero on ‘Zap/5-1’
Oct 25 22:57:12 VERBOSE[23023] logger.c: – Stopped music on hold on
Zap/5-1
Oct 25 22:57:12 DEBUG[23023] cdr_addon_mysql. c: cdr_mysql: inserting
a CDR record.
Oct 25 22:57:12 DEBUG[23023] cdr_addon_mysql. c: cdr_mysql: SQL
command as follows: INSERT INTO cdr
(calldate,clid, src,dst,dcontext ,channel, dstchannel, lastapp,lastdata ,d
uration,billsec, disposition, amaflags, accountcode, uniqueid) VALUES
(‘2006-10-25 22:54:56’,’’ ,’’,‘3335’ ,‘ivr-8’, ‘Zap/5-1’,‘SIP/ 3335-
09834470’,‘Dial’ ,‘SIP/3335| 20|tr’,136, 136,‘ANSWERED’ ,3,’’,‘116182409 1
.1231’)
Oct 25 22:57:12 DEBUG[23023] chan_zap.c: Hangup: channel: 5 index =
0, normal = 19, callwait = -1, thirdcall = -1
Oct 25 22:57:12 DEBUG[23023] chan_zap.c: disabled echo cancellation
on channel 5
Oct 25 22:57:12 DEBUG[23023] chan_zap.c: Set option TDD MODE, value:
OFF(0) on Zap/5-1
Oct 25 22:57:12 DEBUG[23023] chan_zap.c: Updated conferencing on 5,
with 0 conference users
Oct 25 22:57:12 VERBOSE[23023] logger.c: – Hungup 'Zap/5-1’
Oct 25 22:57:27 DEBUG[15251] chan_sip.c: Stopping retransmission
on ‘73414b44134f1c3960 [email protected] 192.168.236. 12’ of Request 102:
Match Found