[28860] manager.c: Manager received command 'Command' Feb 2 00:48:49 DEBUG[28860] manager.c: Manager received command 'Command' Feb 2 00:48:58 DEBUG[2636] chan_sip.c: Setting NAT on RTP to 0 Feb 2 00:48:58 DEBUG[2636] chan_sip.c: Stopping retransmission on '931172758@192.168.140.115' of Response 1: Match Found Feb 2 00:48:58 DEBUG[2636] chan_sip.c: Setting NAT on RTP to 0 Feb 2 00:48:58 DEBUG[2636] chan_sip.c: Checking SIP call limits for device 1001 Feb 2 00:48:58 DEBUG[2636] chan_sip.c: build_route: Contact hop: Feb 2 00:48:58 VERBOSE[28935] logger.c: -- Executing AGI("SIP/1001-4efe", "allpage.agi") in new stack Feb 2 00:48:58 VERBOSE[28935] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/allpage.agi Feb 2 00:48:59 VERBOSE[2607] logger.c: -- Remote UNIX connection Feb 2 00:48:59 VERBOSE[28939] logger.c: -- Remote UNIX connection disconnected Feb 2 00:48:59 VERBOSE[2607] logger.c: -- Remote UNIX connection Feb 2 00:48:59 VERBOSE[28941] logger.c: -- Remote UNIX connection disconnected Feb 2 00:48:59 DEBUG[28942] manager.c: Manager received command 'Login' Feb 2 00:48:59 VERBOSE[28942] logger.c: == Parsing '/etc/asterisk/manager.conf': Feb 2 00:48:59 VERBOSE[28942] logger.c: == Parsing '/etc/asterisk/manager.conf': Found Feb 2 00:48:59 VERBOSE[28942] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Feb 2 00:48:59 VERBOSE[28942] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found Feb 2 00:48:59 WARNING[28942] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf Feb 2 00:48:59 DEBUG[28942] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer Feb 2 00:48:59 DEBUG[28942] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer Feb 2 00:48:59 DEBUG[28942] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 Feb 2 00:48:59 DEBUG[28942] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 Feb 2 00:48:59 VERBOSE[28942] logger.c: == Manager 'admin' logged on from 127.0.0.1 Feb 2 00:48:59 DEBUG[28943] manager.c: Manager received command 'Login' Feb 2 00:48:59 VERBOSE[28943] logger.c: == Parsing '/etc/asterisk/manager.conf': Feb 2 00:48:59 VERBOSE[28943] logger.c: == Parsing '/etc/asterisk/manager.conf': Found Feb 2 00:48:59 VERBOSE[28943] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Feb 2 00:48:59 VERBOSE[28943] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found Feb 2 00:48:59 WARNING[28943] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf Feb 2 00:48:59 DEBUG[28943] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer Feb 2 00:48:59 DEBUG[28943] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer Feb 2 00:48:59 DEBUG[28943] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 Feb 2 00:48:59 DEBUG[28943] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 Feb 2 00:48:59 VERBOSE[28943] logger.c: == Manager 'admin' logged on from 127.0.0.1 Feb 2 00:48:59 VERBOSE[28935] logger.c: allpage.agi: Found extension 1001 in use. Feb 2 00:48:59 VERBOSE[28935] logger.c: allpage.agi: Adding extension 1003 to call list Feb 2 00:48:59 VERBOSE[28935] logger.c: allpage.agi: Adding extension 1002 to call list Feb 2 00:48:59 VERBOSE[28935] logger.c: == allpage.agi: NOT Adding extension 1001 to call list Feb 2 00:48:59 VERBOSE[28935] logger.c: allpage.agi: Doing 1003 Feb 2 00:48:59 VERBOSE[28935] logger.c: allpage.agi: Doing 1002 Feb 2 00:48:59 VERBOSE[28935] logger.c: -- AGI Script allpage.agi completed, returning 0 Feb 2 00:48:59 VERBOSE[28935] logger.c: -- Executing MeetMe("SIP/1001-4efe", "999|dmq") in new stack Feb 2 00:48:59 DEBUG[28935] app_meetme.c: Building dynamic conference '999' Feb 2 00:48:59 VERBOSE[28935] logger.c: -- Created MeetMe conference 1023 for conference '999' Feb 2 00:48:59 DEBUG[28935] app_meetme.c: Placed channel SIP/1001-4efe in ZAP conf 1023 Feb 2 00:48:59 DEBUG[2636] chan_sip.c: Stopping retransmission on '931172758@192.168.140.115' of Response 2: Match Found Feb 2 00:48:59 DEBUG[28935] chan_sip.c: Oooh, format changed to 8 Feb 2 00:48:59 DEBUG[28943] manager.c: Manager received command 'Originate' Feb 2 00:48:59 DEBUG[28943] chan_sip.c: Setting NAT on RTP to 0 Feb 2 00:48:59 DEBUG[28943] chan_sip.c: Outgoing Call for 1002 Feb 2 00:48:59 DEBUG[28942] manager.c: Manager received command 'Originate' Feb 2 00:48:59 DEBUG[28942] chan_sip.c: Setting NAT on RTP to 0 Feb 2 00:48:59 DEBUG[2636] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '4d2ec6017f11be751117b06e71eab1b6@192.168.140.50' Request 102: Found Feb 2 00:48:59 DEBUG[2636] chan_sip.c: Acked pending invite 102 Feb 2 00:48:59 DEBUG[2636] chan_sip.c: Stopping retransmission on '4d2ec6017f11be751117b06e71eab1b6@192.168.140.50' of Request 102: Match Found Feb 2 00:48:59 VERBOSE[2636] logger.c: -- Got SIP response 486 "Busy Here" back from 192.168.140.115 Feb 2 00:48:59 VERBOSE[28943] logger.c: > Channel SIP/1002-0876 was never answered. Feb 2 00:48:59 DEBUG[28943] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Feb 2 00:48:59 DEBUG[28943] 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 ('2006-02-02 00:48:59','System Page','','','all-page', 'SIP/1002-0876','','','',0,0,'BUSY',3,'','1138841339.215') Feb 2 00:48:59 DEBUG[28943] chan_sip.c: update_call_counter(1002) - decrement call limit counter Feb 2 00:48:59 DEBUG[28943] manager.c: Manager received command 'Logoff' Feb 2 00:48:59 VERBOSE[28943] logger.c: == Manager 'admin' logged off from 127.0.0.1 Feb 2 00:48:59 DEBUG[28942] chan_sip.c: Outgoing Call for 1003 Feb 2 00:48:59 DEBUG[2636] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '1a5e0a2b5d6cf84324acc4196d4628ae@192.168.140.50' Request 102: Found Feb 2 00:48:59 DEBUG[2636] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '1a5e0a2b5d6cf84324acc4196d4628ae@192.168.140.50' Request 102: Found [2] 28950 Feb 2 00:49:00 DEBUG[2636] chan_sip.c: Stopping retransmission on '1a5e0a2b5d6cf84324acc4196d4628ae@192.168.140.50' of Request 102: Match Found Feb 2 00:49:00 DEBUG[2636] chan_sip.c: build_route: Contact hop: Feb 2 00:49:00 VERBOSE[28942] logger.c: > Channel SIP/1003-690a was answered. Feb 2 00:49:00 DEBUG[28942] manager.c: Manager received command 'Logoff' Feb 2 00:49:00 VERBOSE[28942] logger.c: == Manager 'admin' logged off from 127.0.0.1 Feb 2 00:49:00 VERBOSE[28948] logger.c: == Starting SIP/1003-690a at all-page,,1 failed so falling back to exten 's' Feb 2 00:49:00 VERBOSE[28948] logger.c: == Starting SIP/1003-690a at all-page,s,1 still failed so falling back to context 'default' Feb 2 00:49:00 VERBOSE[28948] logger.c: -- Executing Playback("SIP/1003-690a", "vm-goodbye") in new stack Feb 2 00:49:00 DEBUG[28948] channel.c: Scheduling timer at 160 sample intervals Feb 2 00:49:00 VERBOSE[28948] logger.c: -- Playing 'vm-goodbye' (language 'en') -bash: strace: command not found [root@asterisk1 ~]# Feb 2 00:49:00 DEBUG[2636] chan_sip.c: Acked pending invite 102 Feb 2 00:49:00 DEBUG[2636] chan_sip.c: Stopping retransmission on '1a5e0a2b5d6cf84324acc4196d4628ae@192.168.140.50' of Request 102: Match Found Feb 2 00:49:00 DEBUG[2636] chan_sip.c: build_route: Contact hop: Feb 2 00:49:00 VERBOSE[28942] logger.c: > Channel SIP/1003-690a was answered. Feb 2 00:49:00 DEBUG[28942] manager.c: Manager received command 'Logoff' Feb 2 00:49:00 VERBOSE[28942] logger.c: == Manager 'admin' logged off from 127.0.0.1 Feb 2 00:49:00 VERBOSE[28948] logger.c: == Starting SIP/1003-690a at all-page,,1 failed so falling back to exten 's' Feb 2 00:49:00 VERBOSE[28948] logger.c: == Starting SIP/1003-690a at all-page,s,1 still failed so falling back to context 'default' Feb 2 00:49:00 VERBOSE[28948] logger.c: -- Executing Playback("SIP/1003-690a", "vm-goodbye") in new stack Feb 2 00:49:00 DEBUG[28948] channel.c: Scheduling timer at 160 sample intervals Feb 2 00:49:00 VERBOSE[28948] logger.c: -- Playing 'vm-goodbye' (language 'en') Feb 2 00:49:01 DEBUG[28948] channel.c: Scheduling timer at 0 sample intervals Feb 2 00:49:01 DEBUG[28948] channel.c: Scheduling timer at 0 sample intervals Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing Macro("SIP/1003-690a", "hangupcall") in new stack Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing ResetCDR("SIP/1003-690a", "w") in new stack Feb 2 00:49:01 DEBUG[28948] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Feb 2 00:49:01 DEBUG[28948] 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 ('2006-02-02 00:49:00','System Page','','','all-page', 'SIP/1003-690a','','ResetCDR','w',1,0,'ANSWERED',3,'','1138841339.216') Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing NoCDR("SIP/1003-690a", "") in new stack Feb 2 00:49:01 WARNING[28948] cdr.c: CDR on channel 'SIP/1003-690a' not posted Feb 2 00:49:01 WARNING[28948] cdr.c: CDR on channel 'SIP/1003-690a' lacks end Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing Wait("SIP/1003-690a", "5") in new stack Feb 2 00:49:01 DEBUG[28948] channel.c: Scheduling timer at 0 sample intervals Feb 2 00:49:01 DEBUG[28948] channel.c: Scheduling timer at 0 sample intervals Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing Macro("SIP/1003-690a", "hangupcall") in new stack Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing ResetCDR("SIP/1003-690a", "w") in new stack Feb 2 00:49:01 DEBUG[28948] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Feb 2 00:49:01 DEBUG[28948] 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 ('2006-02-02 00:49:00','System Page','','','all-page', 'SIP/1003-690a','','ResetCDR','w',1,0,'ANSWERED',3,'','1138841339.216') Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing NoCDR("SIP/1003-690a", "") in new stack Feb 2 00:49:01 WARNING[28948] cdr.c: CDR on channel 'SIP/1003-690a' not posted Feb 2 00:49:01 WARNING[28948] cdr.c: CDR on channel 'SIP/1003-690a' lacks end Feb 2 00:49:01 VERBOSE[28948] logger.c: -- Executing Wait("SIP/1003-690a", "5") in new stack Feb 2 00:49:05 DEBUG[28935] chan_zap.c: Hangup: channel: -2 index = 0, normal = 33, callwait = -1, thirdcall = -1 Feb 2 00:49:05 DEBUG[28935] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/pseudo-105467459 Feb 2 00:49:05 DEBUG[28935] chan_zap.c: Updated conferencing on -2, with 0 conference users Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Hungup 'Zap/pseudo-105467459' Feb 2 00:49:05 VERBOSE[28935] logger.c: == Spawn extension (from-internal, 999, 2) exited non-zero on 'SIP/1001-4efe' Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing Macro("SIP/1001-4efe", "hangupcall") in new stack Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing ResetCDR("SIP/1001-4efe", "w") in new stack Feb 2 00:49:05 DEBUG[28935] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Feb 2 00:49:05 DEBUG[28935] 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 ('2006-02-02 00:48:58','\"device\" <1001>','1001','999','from-internal', 'SIP/1001-4efe','','ResetCDR','w',7,6,'ANSWERED',3,'','1138841338.213') Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing NoCDR("SIP/1001-4efe", "") in new stack Feb 2 00:49:05 WARNING[28935] cdr.c: CDR on channel 'SIP/1001-4efe' not posted Feb 2 00:49:05 WARNING[28935] cdr.c: CDR on channel 'SIP/1001-4efe' lacks end Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing Wait("SIP/1001-4efe", "5") in new stack Feb 2 00:49:05 VERBOSE[28935] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1001-4efe' in macro 'hangupcall' Feb 2 00:49:05 VERBOSE[28935] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1001-4efe' Feb 2 00:49:05 DEBUG[28935] chan_sip.c: update_call_counter(1001) - decrement call limit counter Feb 2 00:49:05 DEBUG[28935] chan_zap.c: Hangup: channel: -2 index = 0, normal = 33, callwait = -1, thirdcall = -1 Feb 2 00:49:05 DEBUG[28935] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/pseudo-105467459 Feb 2 00:49:05 DEBUG[28935] chan_zap.c: Updated conferencing on -2, with 0 conference users Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Hungup 'Zap/pseudo-105467459' Feb 2 00:49:05 VERBOSE[28935] logger.c: == Spawn extension (from-internal, 999, 2) exited non-zero on 'SIP/1001-4efe' Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing Macro("SIP/1001-4efe", "hangupcall") in new stack Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing ResetCDR("SIP/1001-4efe", "w") in new stack Feb 2 00:49:05 DEBUG[28935] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Feb 2 00:49:05 DEBUG[28935] 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 ('2006-02-02 00:48:58','\"device\" <1001>','1001','999','from-internal', 'SIP/1001-4efe','','ResetCDR','w',7,6,'ANSWERED',3,'','1138841338.213') Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing NoCDR("SIP/1001-4efe", "") in new stack Feb 2 00:49:05 WARNING[28935] cdr.c: CDR on channel 'SIP/1001-4efe' not posted Feb 2 00:49:05 WARNING[28935] cdr.c: CDR on channel 'SIP/1001-4efe' lacks end Feb 2 00:49:05 VERBOSE[28935] logger.c: -- Executing Wait("SIP/1001-4efe", "5") in new stack Feb 2 00:49:05 VERBOSE[28935] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1001-4efe' in macro 'hangupcall' Feb 2 00:49:05 VERBOSE[28935] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1001-4efe' Feb 2 00:49:05 DEBUG[28935] chan_sip.c: update_call_counter(1001) - decrement call limit counter Feb 2 00:49:06 VERBOSE[28948] logger.c: -- Executing Hangup("SIP/1003-690a", "") in new stack Feb 2 00:49:06 VERBOSE[28948] logger.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/1003-690a' in macro 'hangupcall' Feb 2 00:49:06 VERBOSE[28948] logger.c: == Spawn extension (default, s, 2) exited non-zero on 'SIP/1003-690a' Feb 2 00:49:06 DEBUG[28948] chan_sip.c: update_call_counter(1003) - decrement call limit counter Feb 2 00:49:06 DEBUG[2636] chan_sip.c: Stopping retransmission on '1a5e0a2b5d6cf84324acc4196d4628ae@192.168.140.50' of Request 103: Match Found Feb 2 00:49:06 VERBOSE[28948] logger.c: -- Executing Hangup("SIP/1003-690a", "") in new stack Feb 2 00:49:06 VERBOSE[28948] logger.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/1003-690a' in macro 'hangupcall' Feb 2 00:49:06 VERBOSE[28948] logger.c: == Spawn extension (default, s, 2) exited non-zero on 'SIP/1003-690a' Feb 2 00:49:06 DEBUG[28948] chan_sip.c: update_call_counter(1003) - decrement call limit counter Feb 2 00:49:06 DEBUG[2636] chan_sip.c: Stopping retransmission on '1a5e0a2b5d6cf84324acc4196d4628ae@192.168.140.50' of Request 103: Match Found [root@asterisk1 ~]#