Dec 21 10:08:19 DEBUG[13267]: ##### Testing 81.174.4.8 with 192.168.0.0 Dec 21 10:08:19 DEBUG[13267]: Target address 81.174.4.8 is not local, substituting externip Dec 21 10:08:19 DEBUG[13267]: Setting NAT on RTP to 4 Dec 21 10:08:20 DEBUG[13267]: Stopping retransmission on '896073903@81.174.4.8' of Response 1: Found Dec 21 10:08:20 DEBUG[13267]: Setting NAT on RTP to 4 Dec 21 10:08:20 DEBUG[13267]: Check for res for 9 Dec 21 10:08:20 DEBUG[13267]: Call from user '9' is 1 out of 0 Dec 21 10:08:20 DEBUG[13267]: build_route: Contact hop: sergio Dec 21 10:08:20 DEBUG[13307]: Device 'SIP/9' changed to state '2' Dec 21 10:08:20 VERBOSE[13308]: -- Executing NoOp("SIP/9-a9c6 ", "Chiamata a interni SIP") in new stack Dec 21 10:08:20 VERBOSE[13308]: -- Executing Macro("SIP/9-a9c6 ", "interni|SIP|1|90") in new stack Dec 21 10:08:20 VERBOSE[13308]: -- Executing NoCDR("SIP/9-a9c6 ", "") in new stack Dec 21 10:08:20 WARNING[13308]: CDR on channel 'SIP/9-a9c6' not posted Dec 21 10:08:20 WARNING[13308]: CDR on channel 'SIP/9-a9c6' lacks end Dec 21 10:08:20 VERBOSE[13308]: -- Executing SetVar("SIP/9-a9c6 ", "_ALERT_INFO=") in new stack Dec 21 10:08:20 VERBOSE[13308]: -- Executing Answer("SIP/9-a9c6 ", "") in new stack Dec 21 10:08:20 VERBOSE[13308]: -- Executing Dial("SIP/9-a9c6 ", "SIP/1|90|Tt") in new stack Dec 21 10:08:20 DEBUG[13308]: Setting NAT on RTP to 0 Dec 21 10:08:20 DEBUG[13308]: ##### Testing 192.168.0.101 with 192.168.0.0 Dec 21 10:08:20 DEBUG[13308]: Outgoing Call for 1 Dec 21 10:08:20 DEBUG[13308]: Call from user '1' is 1 out of 0 Dec 21 10:08:20 VERBOSE[13308]: -- Called 1 Dec 21 10:08:20 DEBUG[13267]: Stopping retransmission on '896073903@81.174.4.8' of Response 2: Found Dec 21 10:08:20 DEBUG[13267]: (Provisional) Stopping retransmission (but retaining packet) on '18b75 fb542a2a4e13a2935d233269083@192.168.0.100' Request 102: Found Dec 21 10:08:20 DEBUG[13267]: (Provisional) Stopping retransmission (but retaining packet) on '18b75 fb542a2a4e13a2935d233269083@192.168.0.100' Request 102: Found Dec 21 10:08:20 VERBOSE[13308]: -- SIP/1-e207 is ringing Dec 21 10:08:20 DEBUG[13308]: Driver for channel 'SIP/9-a9c6' does not support indication 3, emulati ng it Dec 21 10:08:20 DEBUG[13308]: Scheduling timer at 160 sample intervals Dec 21 10:08:20 DEBUG[13309]: Device 'SIP/1' changed to state '2' Dec 21 10:08:20 DEBUG[13309]: Device 'SIP/1' changed to state '2' Dec 21 10:08:20 DEBUG[13308]: Generator got voice, switching to phase locked mode Dec 21 10:08:20 DEBUG[13308]: Scheduling timer at 0 sample intervals Dec 21 10:08:20 DEBUG[13308]: Ooh, format changed from unknown to alaw Dec 21 10:08:22 DEBUG[13267]: Acked pending invite 102 Dec 21 10:08:22 DEBUG[13267]: Stopping retransmission on '18b75fb542a2a4e13a2935d233269083@192.168.0 .100' of Request 102: Found Dec 21 10:08:22 DEBUG[13267]: build_route: Contact hop: Dec 21 10:08:22 VERBOSE[13308]: -- SIP/1-e207 answered SIP/9-a9c6 Dec 21 10:08:22 DEBUG[13308]: Scheduling timer at 0 sample intervals Dec 21 10:08:22 VERBOSE[13308]: -- Attempting native bridge of SIP/9-a9c6 and SIP/1-e207 Dec 21 10:08:22 DEBUG[13308]: Ooh, format changed from unknown to alaw Dec 21 10:08:33 VERBOSE[13267]: -- Started music on hold, class 'default', on SIP/9-a9c6 Dec 21 10:08:33 DEBUG[13267]: Scheduling timer at 160 sample intervals Dec 21 10:08:33 DEBUG[13308]: Generator got voice, switching to phase locked mode Dec 21 10:08:33 DEBUG[13308]: Scheduling timer at 0 sample intervals Dec 21 10:08:33 DEBUG[13308]: Difference is 680, ms is 105 Dec 21 10:08:33 DEBUG[13267]: Stopping retransmission on '18b75fb542a2a4e13a2935d233269083@192.168.0 .100' of Response 101: Found Dec 21 10:08:35 VERBOSE[13267]: -- Stopped music on hold on SIP/9-a9c6 Dec 21 10:08:35 DEBUG[13267]: Scheduling timer at 0 sample intervals Dec 21 10:08:35 DEBUG[13267]: Stopping retransmission on '18b75fb542a2a4e13a2935d233269083@192.168.0 .100' of Response 102: Found Dec 21 10:09:29 VERBOSE[13267]: -- Started music on hold, class 'default', on SIP/9-a9c6 Dec 21 10:09:29 DEBUG[13267]: Scheduling timer at 160 sample intervals Dec 21 10:09:29 DEBUG[13308]: Generator got voice, switching to phase locked mode Dec 21 10:09:29 DEBUG[13308]: Scheduling timer at 0 sample intervals Dec 21 10:09:29 DEBUG[13308]: Difference is 760, ms is 115 Dec 21 10:09:29 DEBUG[13267]: Stopping retransmission on '18b75fb542a2a4e13a2935d233269083@192.168.0 .100' of Response 103: Found Dec 21 10:09:30 DEBUG[13267]: ##### Testing 192.168.0.101 with 192.168.0.0 Dec 21 10:09:30 DEBUG[13267]: ##### Testing 192.168.0.101 with 0.0.0.0 Dec 21 10:09:30 DEBUG[13267]: ##### Testing 192.168.0.101 with 192.168.0.0 Dec 21 10:09:30 DEBUG[13267]: Setting NAT on RTP to 0 Dec 21 10:09:30 DEBUG[13267]: Stopping retransmission on '001192d9-83070009-33d99298-59babdc1@192.16 8.0.101' of Response 101: Found Dec 21 10:09:30 DEBUG[13267]: ##### Testing 192.168.0.101 with 0.0.0.0 Dec 21 10:09:30 DEBUG[13267]: ##### Testing 192.168.0.101 with 192.168.0.0 Dec 21 10:09:30 DEBUG[13267]: Setting NAT on RTP to 0