i am seeing something like No provider found, checking channel drivers for SIP
wonder if that is the problem, i have tested the trunk in freepbx and it work, and a2billing its pointed correctly trunk name matching
anyone got any ideas?
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: Setting NAT on RTP to On
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: Allocating new SIP dialog for
[email protected] - INVITE (With RTP)
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: Setting NAT on RTP to On
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: = Found Their Call ID:
[email protected] Their Tag 4d0cf28a7b36aafo1 Our tag: as00d763c0
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: Stopping retransmission on '
[email protected]' of Response 101: Match Found
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: = Found Their Call ID:
[email protected] Their Tag 4d0cf28a7b36aafo1 Our tag: as00d763c0
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: Setting NAT on RTP to On
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: T38 state changed to 0 on channel <none>
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: We're settling with these formats: 0x1 (g723)
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: Checking SIP call limits for device 1697848084
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: Updating call counter for incoming call
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: *** Our native formats are 0x1 (g723)
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: *** Joint capabilities are 0x1 (g723)
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: *** Our capabilities are 0x5 (g723|ulaw)
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x1 (g723)
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: This channel will not be able to handle video.
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: build_route: Contact hop: <sip:
[email protected]:5060>
[2010-02-08 13:39:51] DEBUG[3257] chan_sip.c: SIP/1697848084-14b70b30: New call is still down.... Trying...
[2010-02-08 13:39:51] DEBUG[3257] devicestate.c: Notification of state change to be queued on device/channel SIP/1697848084-14b70b30
[2010-02-08 13:39:51] DEBUG[3257] devicestate.c: Notification of state change to be queued on device/channel SIP/1697848084
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: No provider found, checking channel drivers for SIP - 1697848084-14b70b30
[2010-02-08 13:39:51] DEBUG[3255] chan_sip.c: Checking device state for peer 1697848084-14b70b30
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: Changing state for SIP/1697848084-14b70b30 - state 4 (Invalid)
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: No provider found, checking channel drivers for SIP - 1697848084
[2010-02-08 13:39:51] DEBUG[3255] chan_sip.c: Checking device state for peer 1697848084
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: Changing state for SIP/1697848084 - state 1 (Not in use)
[2010-02-08 13:39:51] DEBUG[3277] app_queue.c: Device 'SIP/1697848084-14b70b30' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.
[2010-02-08 13:39:51] DEBUG[3277] app_queue.c: Device 'SIP/1697848084' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2010-02-08 13:39:51] DEBUG[16300] pbx.c: Launching 'Answer'
[2010-02-08 13:39:51] VERBOSE[16300] logger.c: -- Executing [12127773456@a2billing:1] Answer("SIP/1697848084-14b70b30", "") in new stack
[2010-02-08 13:39:51] DEBUG[16300] devicestate.c: Notification of state change to be queued on device/channel SIP/1697848084-14b70b30
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: No provider found, checking channel drivers for SIP - 1697848084-14b70b30
[2010-02-08 13:39:51] DEBUG[3255] chan_sip.c: Checking device state for peer 1697848084-14b70b30
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: Changing state for SIP/1697848084-14b70b30 - state 4 (Invalid)
[2010-02-08 13:39:51] DEBUG[3277] app_queue.c: Device 'SIP/1697848084-14b70b30' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.
[2010-02-08 13:39:51] DEBUG[16300] devicestate.c: Notification of state change to be queued on device/channel SIP/1697848084
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: No provider found, checking channel drivers for SIP - 1697848084
[2010-02-08 13:39:51] DEBUG[3255] chan_sip.c: Checking device state for peer 1697848084
[2010-02-08 13:39:51] DEBUG[3255] devicestate.c: Changing state for SIP/1697848084 - state 1 (Not in use)
[2010-02-08 13:39:51] DEBUG[3277] app_queue.c: Device 'SIP/1697848084' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2010-02-08 13:39:51] DEBUG[16300] chan_sip.c: SIP answering channel: SIP/1697848084-14b70b30
[2010-02-08 13:39:51] DEBUG[16300] chan_sip.c: Setting framing from config on incoming call
[2010-02-08 13:39:51] DEBUG[16300] chan_sip.c: ** Our capability: 0x1 (g723) Video flag: True
[2010-02-08 13:39:51] DEBUG[16300] chan_sip.c: ** Our prefcodec: 0x0 (nothing)
[2010-02-08 13:39:51] DEBUG[16300] chan_sip.c: -- Done with adding codecs to SDP
[2010-02-08 13:39:51] DEBUG[16300] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=22)
[2010-02-08 13:39:51] DEBUG[16300] chan_sip.c: Done building SDP. Settling with this capability: 0x1 (g723)
[2010-02-08 13:39:51] DEBUG[16300] pbx.c: Launching 'Wait'
[2010-02-08 13:39:51] VERBOSE[16300] logger.c: -- Executing [12127773456@a2billing:2] Wait("SIP/1697848084-14b70b30", "1") in new stack
[2010-02-08 13:39:52] DEBUG[3257] chan_sip.c: = Found Their Call ID:
[email protected] Their Tag 4d0cf28a7b36aafo1 Our tag: as78960aa2
[2010-02-08 13:39:52] DEBUG[3257] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[2010-02-08 13:39:52] DEBUG[3257] chan_sip.c: Stopping retransmission on '
[email protected]' of Response 102: Match Found
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: Sending dtmf: 35 (#), at 10.11.12.215
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: Sending dtmf: 35 (#), at 10.11.12.215
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:52] DEBUG[16300] rtp.c: - RTP 2833 Event: 0000000b (len = 4)
[2010-02-08 13:39:53] DEBUG[16300] pbx.c: Launching 'DeadAGI'
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: -- Executing [12127773456@a2billing:3] DeadAGI("SIP/1697848084-14b70b30", "a2billing.php|1") in new stack
[2010-02-08 13:39:53] WARNING[16300] res_agi.c: Running DeadAGI on a live channel will cause problems, please use AGI
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/a2billing.php
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_request: a2billing.php
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_channel: SIP/1697848084-14b70b30
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_language: en
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_type: SIP
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_uniqueid: 1265650791.42
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_callerid: 1697848084
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_calleridname: unknown
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_callingpres: 0
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_callingani2: 0
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_callington: 0
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_callingtns: 0
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_dnid: 12127773456
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_rdnis: unknown
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_context: a2billing
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_extension: 12127773456
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_priority: 3
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_enhanced: 0.0
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >> agi_accountcode: 1697848084
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: AGI Tx >>
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: -- AGI Script a2billing.php completed, returning 0
[2010-02-08 13:39:53] DEBUG[16300] pbx.c: Launching 'Hangup'
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: -- Executing [12127773456@a2billing:4] Hangup("SIP/1697848084-14b70b30", "") in new stack
[2010-02-08 13:39:53] DEBUG[16300] pbx.c: Spawn extension (a2billing,12127773456,4) exited non-zero on 'SIP/1697848084-14b70b30'
[2010-02-08 13:39:53] VERBOSE[16300] logger.c: == Spawn extension (a2billing, 12127773456, 4) exited non-zero on 'SIP/1697848084-14b70b30'
[2010-02-08 13:39:53] DEBUG[16300] channel.c: Soft-Hanging up channel 'SIP/1697848084-14b70b30'
[2010-02-08 13:39:53] DEBUG[16300] channel.c: Hanging up channel 'SIP/1697848084-14b70b30'
[2010-02-08 13:39:53] DEBUG[16300] chan_sip.c: Hangup call SIP/1697848084-14b70b30, SIP callid
[email protected])
[2010-02-08 13:39:53] DEBUG[16300] devicestate.c: Notification of state change to be queued on device/channel SIP/1697848084-14b70b30
[2010-02-08 13:39:53] DEBUG[16300] devicestate.c: Notification of state change to be queued on device/channel SIP/1697848084
[2010-02-08 13:39:53] DEBUG[3255] devicestate.c: No provider found, checking channel drivers for SIP - 1697848084-14b70b30
[2010-02-08 13:39:53] DEBUG[3255] chan_sip.c: Checking device state for peer 1697848084-14b70b30
[2010-02-08 13:39:53] DEBUG[3255] devicestate.c: Changing state for SIP/1697848084-14b70b30 - state 4 (Invalid)
[2010-02-08 13:39:53] DEBUG[3255] devicestate.c: No provider found, checking channel drivers for SIP - 1697848084
[2010-02-08 13:39:53] DEBUG[3255] chan_sip.c: Checking device state for peer 1697848084
[2010-02-08 13:39:53] DEBUG[3255] devicestate.c: Changing state for SIP/1697848084 - state 1 (Not in use)
[2010-02-08 13:39:53] DEBUG[3277] app_queue.c: Device 'SIP/1697848084-14b70b30' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.
[2010-02-08 13:39:53] DEBUG[3277] app_queue.c: Device 'SIP/1697848084' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2010-02-08 13:39:53] DEBUG[16300] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[2010-02-08 13:39:53] DEBUG[16300] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2010-02-08 13:39:51','1697848084','1697848084','12127773456','a2billing', 'SIP/1697848084-14b70b30','','Hangup','',2,2,'ANSWERED',2,'1697848084')
[2010-02-08 13:39:53] DEBUG[3257] chan_sip.c: = Found Their Call ID:
[email protected] Their Tag 4d0cf28a7b36aafo1 Our tag: as78960aa2
[2010-02-08 13:39:53] DEBUG[3257] chan_sip.c: Stopping retransmission on '
[email protected]' of Request 102: Match Found
[2010-02-08 13:39:53] VERBOSE[3257] logger.c: Really destroying SIP dialog '
[email protected]' Method: ACK
[2010-02-08 13:40:02] DEBUG[16302] manager.c: Manager received command 'login'
[2010-02-08 13:40:02] VERBOSE[16302] logger.c: == Parsing '/etc/asterisk/manager.conf': [2010-02-08 13:40:02] DEBUG[16302] config.c: Parsing /etc/asterisk/manager.conf
[2010-02-08 13:40:02] VERBOSE[16302] logger.c: Found
[2010-02-08 13:40:02] VERBOSE[16302] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [2010-02-08 13:40:02] DEBUG[16302] config.c: Parsing /etc/asterisk/manager_additional.conf
[2010-02-08 13:40:02] VERBOSE[16302] logger.c: Found
[2010-02-08 13:40:02] VERBOSE[16302] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [2010-02-08 13:40:02] DEBUG[16302] config.c: Parsing /etc/asterisk/manager_custom.conf
[2010-02-08 13:40:02] VERBOSE[16302] logger.c: Found
[2010-02-08 13:40:02] DEBUG[16302] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
[2010-02-08 13:40:02] DEBUG[16302] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
[2010-02-08 13:40:02] DEBUG[16302] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
[2010-02-08 13:40:02] DEBUG[16302] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
[2010-02-08 13:40:02] VERBOSE[16302] logger.c: == Manager 'admin' logged on from 127.0.0.1