Yeah, your waittime parameter in your call file is set to 45 seconds. db
On Wed, 2007-01-31 at 21:52 -0500, Matthew Rubenstein wrote: > I used the "FreePBX on Debian" HowTo at > http://powerontech.com/freepbx-on-debian.htm to install. I use callfiles > to initiate calls to my SIP carrier. They get my registration, but they > see that my call is interrupted before they can complete the connection. > My Asterisk log shows that the call times out after the time (45s) > specified in my dialplan Dial() command. What is wrong? > > [from /var/log/asterisk/full]: > Jan 30 23:40:35 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:40:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:40:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:40:44 DEBUG[6245] chan_sip.c: Scheduled a registration timeout > for 66.153.22.16 id #17818 > Jan 30 23:40:44 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 606: Found > Jan 30 23:40:44 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 606: Match > Found > Jan 30 23:40:44 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 607: Found > Jan 30 23:40:44 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 607: Match > Found > Jan 30 23:40:44 DEBUG[6245] chan_sip.c: Registration successful > Jan 30 23:40:44 DEBUG[6245] chan_sip.c: Cancelling timeout 17818 > Jan 30 23:41:16 DEBUG[6245] chan_sip.c: Auto destroying call > '[EMAIL PROTECTED]' > Jan 30 23:41:30 VERBOSE[17267] logger.c: -- Attempting call on > Local/[EMAIL PROTECTED]/n for [EMAIL PROTECTED]:1 (Retry 1) > Jan 30 23:41:30 VERBOSE[17269] logger.c: -- Executing > NoOp("Local/[EMAIL PROTECTED],2", "Calling > SIP/[EMAIL PROTECTED]") in new stack > Jan 30 23:41:30 VERBOSE[17269] logger.c: -- Executing > Dial("Local/[EMAIL PROTECTED],2", "SIP/[EMAIL PROTECTED]|45| > M(say-call-2-digits^17182335097)g") in new stack > Jan 30 23:41:30 DEBUG[17269] chan_sip.c: Setting NAT on RTP to 0 > Jan 30 23:41:30 DEBUG[17269] chan_sip.c: Outgoing Call for 16467508273 > Jan 30 23:41:30 VERBOSE[17269] logger.c: -- Called > [EMAIL PROTECTED] > Jan 30 23:41:30 DEBUG[6245] chan_sip.c: Acked pending invite 102 > Jan 30 23:41:30 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:41:30 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 103: Found > Jan 30 23:41:35 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:42:15 VERBOSE[17269] logger.c: -- Nobody picked up in > 45000 ms > Jan 30 23:42:15 DEBUG[17269] chan_sip.c: > update_call_counter(16467508273) - decrement call limit counter > Jan 30 23:42:15 DEBUG[17269] chan_sip.c: Acked pending invite 103 > Jan 30 23:42:15 DEBUG[17269] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 103: Match > Found > Jan 30 23:42:15 DEBUG[17269] app_dial.c: Exiting with > DIALSTATUS=NOANSWER. > Jan 30 23:42:15 VERBOSE[17269] logger.c: -- Executing > NoOp("Local/[EMAIL PROTECTED],2", "Done dialing from") in new > stack > Jan 30 23:42:15 DEBUG[17267] cdr_addon_mysql.c: cdr_mysql: inserting a > CDR record. > Jan 30 23:42:15 DEBUG[17267] 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,uniqueid) > VALUES ('2007-01-30 > 23:42:15','16467508273','16467508273','callTo','ext-jjp-out', 'Local/[EMAIL > PROTECTED],1','','Dial','Local/[EMAIL > PROTECTED]/n',0,0,'FAILED',3,'','1170196890.32') > Jan 30 23:42:15 DEBUG[17267] cdr_addon_mysql.c: cdr_mysql: inserting a > CDR record. > Jan 30 23:42:15 DEBUG[17267] 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,uniqueid) > VALUES ('2007-01-30 23:42:15','','','s','default', > '**Unknown**','','','',0,0,'FAILED',3,'','1170196935.35') > Jan 30 23:42:15 NOTICE[17267] pbx_spool.c: Call failed to go through, > reason 0 > Jan 30 23:42:15 DEBUG[17269] cdr_addon_mysql.c: cdr_mysql: inserting a > CDR record. > Jan 30 23:42:15 DEBUG[17269] 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,uniqueid) > VALUES ('2007-01-30 > 23:41:30','16467508273','16467508273','callFrom','ext-jjp-out', 'Local/[EMAIL > PROTECTED],2','SIP/tu3961-08196340','NoOp','Done dialing from',45,0,'NO > ANSWER',3,'','1170196890.33') > Jan 30 23:42:15 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 103: Match > Not Found > Jan 30 23:42:15 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 103: Match > Found > Jan 30 23:42:29 DEBUG[6245] chan_sip.c: Scheduled a registration timeout > for 66.153.22.16 id #17831 > Jan 30 23:42:29 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 608: Found > Jan 30 23:42:29 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 608: Match > Found > Jan 30 23:42:29 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 609: Found > Jan 30 23:42:29 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 609: Match > Found > Jan 30 23:42:29 DEBUG[6245] chan_sip.c: Registration successful > Jan 30 23:42:29 DEBUG[6245] chan_sip.c: Cancelling timeout 17831 > Jan 30 23:42:35 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:42:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:42:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:42:47 DEBUG[6245] chan_sip.c: Auto destroying call > '[EMAIL PROTECTED]' > Jan 30 23:43:01 DEBUG[6245] chan_sip.c: Auto destroying call > '[EMAIL PROTECTED]' > Jan 30 23:43:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:44:14 DEBUG[6245] chan_sip.c: Scheduled a registration timeout > for 66.153.22.16 id #17842 > Jan 30 23:44:14 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 610: Found > Jan 30 23:44:14 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 610: Match > Found > Jan 30 23:44:14 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 611: Found > Jan 30 23:44:14 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 611: Match > Found > Jan 30 23:44:14 DEBUG[6245] chan_sip.c: Registration successful > Jan 30 23:44:14 DEBUG[6245] chan_sip.c: Cancelling timeout 17842 > Jan 30 23:44:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:44:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:44:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:44:46 DEBUG[6245] chan_sip.c: Auto destroying call > '[EMAIL PROTECTED]' > Jan 30 23:45:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:45:59 DEBUG[6245] chan_sip.c: Scheduled a registration timeout > for 66.153.22.16 id #17853 > Jan 30 23:45:59 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 612: Found > Jan 30 23:45:59 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 612: Match > Found > Jan 30 23:45:59 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 613: Found > Jan 30 23:45:59 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 613: Match > Found > Jan 30 23:45:59 DEBUG[6245] chan_sip.c: Registration successful > Jan 30 23:45:59 DEBUG[6245] chan_sip.c: Cancelling timeout 17853 > Jan 30 23:46:31 DEBUG[6245] chan_sip.c: Auto destroying call > '[EMAIL PROTECTED]' > Jan 30 23:46:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:46:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:46:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:47:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:47:44 DEBUG[6245] chan_sip.c: Scheduled a registration timeout > for 66.153.22.16 id #17864 > Jan 30 23:47:44 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 614: Found > Jan 30 23:47:44 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 614: Match > Found > Jan 30 23:47:44 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 615: Found > Jan 30 23:47:44 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 615: Match > Found > Jan 30 23:47:44 DEBUG[6245] chan_sip.c: Registration successful > Jan 30 23:47:44 DEBUG[6245] chan_sip.c: Cancelling timeout 17864 > Jan 30 23:48:16 DEBUG[6245] chan_sip.c: Auto destroying call > '[EMAIL PROTECTED]' > Jan 30 23:48:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:48:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:48:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:49:29 DEBUG[6245] chan_sip.c: Scheduled a registration timeout > for 66.153.22.16 id #17872 > Jan 30 23:49:29 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 616: Found > Jan 30 23:49:29 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 616: Match > Found > Jan 30 23:49:29 DEBUG[6245] chan_sip.c: (Provisional) Stopping > retransmission (but retaining packet) on > '[EMAIL PROTECTED]' Request 617: Found > Jan 30 23:49:29 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 617: Match > Found > Jan 30 23:49:29 DEBUG[6245] chan_sip.c: Registration successful > Jan 30 23:49:29 DEBUG[6245] chan_sip.c: Cancelling timeout 17872 > Jan 30 23:49:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:50:01 DEBUG[6245] chan_sip.c: Auto destroying call > '[EMAIL PROTECTED]' > Jan 30 23:50:36 DEBUG[6245] chan_sip.c: Stopping retransmission on > '[EMAIL PROTECTED]' of Request 102: Match > Found > Jan 30 23:50:44 DEBUG[6268] manager.c: Manager received command > 'Command' > Jan 30 23:50:44 DEBUG[6268] manager.c: Manager received command > 'Command' _______________________________________________ --Bandwidth and Colocation provided by Easynews.com -- asterisk-users mailing list To UNSUBSCRIBE or update options visit: http://lists.digium.com/mailman/listinfo/asterisk-users
