[Jul 25 08:59:37] DEBUG[28274] chan_dahdi.c: Monitor doohicky got event Polarity Reversal on channel 1 [Jul 25 08:59:37] DEBUG[28274] sig_analog.c: channel (1) - signaling (5) - event (ANALOG_EVENT_POLARITY) [Jul 25 08:59:37] VERBOSE[28274] sig_analog.c: == Starting post polarity CID detection on channel 1 [Jul 25 08:59:37] DEBUG[28274] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Jul 25 08:59:37] DEBUG[28274] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Jul 25 08:59:37] DEBUG[28274] devicestate.c: device 'DAHDI/1-1' state '6' [Jul 25 08:59:37] DEBUG[28301] app_queue.c: Device 'DAHDI/1-1' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Jul 25 08:59:37] DEBUG[28384] sig_analog.c: __analog_ss_thread 1 [Jul 25 08:59:37] VERBOSE[28384] sig_analog.c: -- Starting simple switch on 'DAHDI/1-1' [Jul 25 08:59:39] DEBUG[28384] chan_dahdi.c: CallerID number: 07850-709688, name: (null), flags=4 [Jul 25 08:59:39] DEBUG[28384] sig_analog.c: analog_exception 1 [Jul 25 08:59:39] DEBUG[28384] sig_analog.c: Exception on 14, channel 1 [Jul 25 08:59:39] DEBUG[28384] sig_analog.c: __analog_handle_event 1 [Jul 25 08:59:39] DEBUG[28384] sig_analog.c: Got event ANALOG_EVENT_RINGBEGIN(12) on channel 1 (index 0) [Jul 25 08:59:39] DEBUG[28384] sig_analog.c: Dunno what to do with event 12 on channel 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: analog_exception 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: Exception on 14, channel 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: __analog_handle_event 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: Got event ANALOG_EVENT_RINGOFFHOOK(2) on channel 1 (index 0) [Jul 25 08:59:40] DEBUG[28265] devicestate.c: No provider found, checking channel drivers for DAHDI - 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: Ring detected [Jul 25 08:59:40] DEBUG[28265] devicestate.c: Changing state for DAHDI/1 - state 2 (In use) [Jul 25 08:59:40] DEBUG[28265] devicestate.c: device 'DAHDI/1' state '2' [Jul 25 08:59:40] DEBUG[28301] app_queue.c: Device 'DAHDI/1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jul 25 08:59:40] NOTICE[28384] chan_dahdi.c: Got event 18 (Ring Begin)... [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Detected ring pattern: 0,0,0 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Checking 0,0,0 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Ring pattern check range: 10 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Ring pattern matched in range: -10 to 10 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Ring pattern check range: 10 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Ring pattern matched in range: -10 to 10 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Ring pattern check range: 10 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Ring pattern matched in range: -10 to 10 [Jul 25 08:59:40] VERBOSE[28384] chan_dahdi.c: -- Distinctive Ring matched context BTInbound [Jul 25 08:59:40] DEBUG[28384] pbx.c: Function result is '07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'NoOp' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:1] NoOp("DAHDI/1-1", "07850709688") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Function result is '' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'NoOp' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:2] NoOp("DAHDI/1-1", "") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Function result is '07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Function result is '' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'AGI' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:3] AGI("DAHDI/1-1", "/var/scripts/asterisk/ext_call_in_status.pl,BT,07850709688,") in new stack [Jul 25 08:59:40] VERBOSE[28384] res_agi.c: -- Launched AGI Script /var/scripts/asterisk/ext_call_in_status.pl [Jul 25 08:59:40] ERROR[28384] utils.c: write() returned error: Broken pipe [Jul 25 08:59:40] VERBOSE[28384] res_agi.c: -- AGI Script /var/scripts/asterisk/ext_call_in_status.pl completed, returning 0 [Jul 25 08:59:40] DEBUG[28384] pbx.c: Function result is '07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'NoOp' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:4] NoOp("DAHDI/1-1", "07850709688") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Function result is 'Ian Stringer' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'NoOp' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:5] NoOp("DAHDI/1-1", "Ian Stringer") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VMONITORDATETIME' is '20100725_085940_07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'Macro' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:6] Macro("DAHDI/1-1", "callmonitor,20100725_085940_07850709688") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'ARG1' is '20100725_085940_07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'ARG1' is '20100725_085940_07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'ARG1' is '20100725_085940_07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'ARG1' is '20100725_085940_07850709688' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'MixMonitor' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@macro-callmonitor:1] MixMonitor("DAHDI/1-1", "20100725_085940_07850709688.wav,v(2)V(2)b,/usr/local/bin/lame --preset standard --silent /var/spool/asterisk/monitor/20100725_085940_07850709688.wav /var/spool/asterisk/monitor/20100725_085940_07850709688.mp3 && rm -f /var/spool/asterisk/monitor/20100725_085940_07850709688.wav") in new stack [Jul 25 08:59:40] DEBUG[28384] autochan.c: Created autochan 0xb7501718 to hold channel DAHDI/1-1 (0xb76023e8) [Jul 25 08:59:40] DEBUG[28384] app_macro.c: Executed application: MixMonitor [Jul 25 08:59:40] VERBOSE[28391] app_mixmonitor.c: == Begin MixMonitor Recording DAHDI/1-1 [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VMONITORDATETIME' is '20100725_085940_07850709688' [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'Set' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@macro-callmonitor:2] Set("DAHDI/1-1", "CDR(userfield)=MONITOR:20100725_085940_07850709688.mp3") in new stack [Jul 25 08:59:40] DEBUG[28384] app_macro.c: Executed application: Set [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSYSOP' is NULL [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:7] GotoIf("DAHDI/1-1", "0?MsysOp") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSMS' is NULL [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:8] GotoIf("DAHDI/1-1", "0?Msms") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VWITHHELD' is NULL [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:9] GotoIf("DAHDI/1-1", "0?MNoWHELD") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VWITHHELD' is NULL [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:10] GotoIf("DAHDI/1-1", "0?MSilent") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VWITHHELD' is NULL [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:11] GotoIf("DAHDI/1-1", "0?MPWHELD") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VWITHHELD' is NULL [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:12] GotoIf("DAHDI/1-1", "0?MVMail") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSTATUS' is 'OFF' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:13] GotoIf("DAHDI/1-1", "0?MSpam") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSTATUS' is 'OFF' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:14] GotoIf("DAHDI/1-1", "0?MMsg5101") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSTATUS' is 'OFF' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:15] GotoIf("DAHDI/1-1", "0?MMsg5201") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSTATUS' is 'OFF' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:16] GotoIf("DAHDI/1-1", "0?MMsg5301") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSTATUS' is 'OFF' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:17] GotoIf("DAHDI/1-1", "0?MMsg5401") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSTATUS' is 'OFF' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:18] GotoIf("DAHDI/1-1", "0?MVMail") in new stack [Jul 25 08:59:40] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:40] DEBUG[28384] pbx.c: Result of 'VSTATUS' is 'OFF' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Expression result is '1' [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:19] GotoIf("DAHDI/1-1", "1?MDialOnly") in new stack [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Goto (BTInbound,s,39) [Jul 25 08:59:40] DEBUG[28384] pbx.c: Launching 'Dial' [Jul 25 08:59:40] VERBOSE[28384] pbx.c: -- Executing [s@BTInbound:39] Dial("DAHDI/1-1", "SIP/2002,,trKkg") in new stack [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw) [Jul 25 08:59:40] VERBOSE[28384] netsock.c: == Using UDPTL CoS mark 5 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Allocating new SIP dialog for 22a132aa7cb91a884c1e8d5230def6c8@127.0.0.1:0 - INVITE (No RTP) [Jul 25 08:59:40] DEBUG[28384] rtp_engine.c: Using engine 'asterisk' for RTP instance '0xb754e6e0' [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: Allocated port 2570 for RTP instance '0xb754e6e0' [Jul 25 08:59:40] DEBUG[28384] rtp_engine.c: RTP instance '0xb754e6e0' is setup and ready to go [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: Setup RTCP on RTP instance '0xb754e6e0' [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Setting NAT on RTP to On [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Setting NAT on UDPTL to On [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Jul 25 08:59:40] DEBUG[28384] acl.c: For destination '192.168.0.222', our source address is '192.168.0.55'. [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.0.55:5060 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: *** Joint capabilities are 0x4 (ulaw) [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: *** Our capabilities are 0x80030c7fffff (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|speex16|ilbc|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|siren7|siren14|testlaw|g719) [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: *** Our preferred formats from the incoming channel are 0x4 (ulaw) [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: This channel will not be able to handle video. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable DIALEDTIME. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable ANSWEREDTIME. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable DIALEDPEERNAME. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable DIALEDPEERNUMBER. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable DIALSTATUS. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable MACRO_DEPTH. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable MIXMONITOR_FILENAME. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable AGISTATUS. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable vmonitordatetime. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable vknowncontact. [Jul 25 08:59:40] DEBUG[28384] channel.c: Not copying variable vstatus. [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Outgoing Call for 2002 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Updating call counter for outgoing call [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: This call needs video offers, but there's no video support enabled! [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: We think we can do text [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: This call needs text offers, but there's no text support enabled ! [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: ** Our capability: 0x80000c7f98fe (gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|testlaw) Video flag: False Text flag: False [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: ** Our prefcodec: 0x4 (ulaw) [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Audio is at 5060 [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x8 (alaw) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x2 (gsm) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x10 (g726aal2) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x20 (adpcm) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x40 (slin) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x80 (lpc10) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x800 (g726) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x1000 (g722) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x8000 (slin16) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding codec 0x800000000000 (testlaw) to SDP [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: -- Done with adding codecs to SDP [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Done building SDP. Settling with this capability: 0x80000c7f98fe (gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|testlaw) [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Initializing initreq for method INVITE - callid 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 0 [ 37]: INVITE sip:2002@192.168.0.222 SIP/2.0 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 3 [ 66]: From: "Ian Stringer" ;tag=as45e056db [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 4 [ 28]: To: [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 5 [ 44]: Contact: [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 6 [ 59]: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 7 [ 16]: CSeq: 102 INVITE [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 8 [ 36]: User-Agent: Asterisk PBX 1.8.0-beta1 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 9 [ 35]: Date: Sun, 25 Jul 2010 07:59:40 GMT [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Jul 25 08:59:40] VERBOSE[28384] chan_sip.c: Reliably Transmitting (NAT) to 192.168.0.222:5060: INVITE sip:2002@192.168.0.222 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport Max-Forwards: 70 From: "Ian Stringer" ;tag=as45e056db To: Contact: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 CSeq: 102 INVITE User-Agent: Asterisk PBX 1.8.0-beta1 Date: Sun, 25 Jul 2010 07:59:40 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Type: application/sdp Content-Length: 477 v=0 o=root 1175004532 1175004532 IN IP4 192.168.0.55 s=Asterisk PBX 1.8.0-beta1 c=IN IP4 192.168.0.55 t=0 0 m=audio 2570 RTP/AVP 0 8 3 112 5 10 7 111 9 118 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:112 AAL2-G726-32/8000 a=rtpmap:5 DVI4/8000 a=rtpmap:10 L16/8000 a=rtpmap:7 LPC/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:118 L16/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #28 [Jul 25 08:59:40] DEBUG[28384] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 192.168.0.222:5060 [Jul 25 08:59:40] VERBOSE[28384] app_dial.c: -- Called 2002 [Jul 25 08:59:40] DEBUG[28384] chan_dahdi.c: Requested indication 3 on channel DAHDI/1-1 [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:192.168.0.222:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport From: "Ian Stringer" ;tag=as45e056db To: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 CSeq: 102 INVITE User-Agent: Grandstream HT486 1.0.6.7 Content-Length: 0 <-------------> [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 2 [ 66]: From: "Ian Stringer" ;tag=as45e056db [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 3 [ 28]: To: [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 4 [ 59]: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 6 [ 37]: User-Agent: Grandstream HT486 1.0.6.7 [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 8 [ 0]: [Jul 25 08:59:40] VERBOSE[28290] chan_sip.c: --- (8 headers 0 lines) --- [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: *** SIP TIMER: Cancelling retransmission #28 - INVITE (got response) [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060' Request 102: Found [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: SIP response 100 to standard invite [Jul 25 08:59:40] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:192.168.0.222:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport From: "Ian Stringer" ;tag=as45e056db To: ;tag=d31a3c975c8e538c Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 CSeq: 102 INVITE User-Agent: Grandstream HT486 1.0.6.7 Content-Length: 0 <-------------> [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 0 [ 19]: SIP/2.0 180 Ringing [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 2 [ 66]: From: "Ian Stringer" ;tag=as45e056db [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 3 [ 49]: To: ;tag=d31a3c975c8e538c [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 4 [ 59]: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 6 [ 37]: User-Agent: Grandstream HT486 1.0.6.7 [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: Header 8 [ 0]: [Jul 25 08:59:40] VERBOSE[28290] chan_sip.c: --- (8 headers 0 lines) --- [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060' Request 102: Found [Jul 25 08:59:40] DEBUG[28290] chan_sip.c: SIP response 180 to standard invite [Jul 25 08:59:40] DEBUG[28265] devicestate.c: No provider found, checking channel drivers for SIP - 2002 [Jul 25 08:59:40] DEBUG[28265] chan_sip.c: Checking device state for peer 2002 [Jul 25 08:59:40] DEBUG[28265] devicestate.c: Changing state for SIP/2002 - state 1 (Not in use) [Jul 25 08:59:40] DEBUG[28265] devicestate.c: device 'SIP/2002' state '1' [Jul 25 08:59:40] DEBUG[28301] app_queue.c: Device 'SIP/2002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jul 25 08:59:40] VERBOSE[28384] app_dial.c: -- SIP/2002-00000000 is ringing [Jul 25 08:59:40] DEBUG[28384] chan_dahdi.c: Requested indication 3 on channel DAHDI/1-1 [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: analog_exception 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: Exception on 14, channel 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: __analog_handle_event 1 [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: Got event ANALOG_EVENT_RINGOFFHOOK(2) on channel 1 (index 0) [Jul 25 08:59:40] DEBUG[28384] sig_analog.c: Ring detected [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:40] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:40] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:41] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:41] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: No remote address on RTP instance '0xb754e6e0' so dropping frame [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:192.168.0.222:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport From: "Ian Stringer" ;tag=as45e056db To: ;tag=d31a3c975c8e538c Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 CSeq: 102 INVITE User-Agent: Grandstream HT486 1.0.6.7 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Content-Type: application/sdp Supported: replaces Content-Length: 157 v=0 o=2002 8000 8000 IN IP4 192.168.0.222 s=SIP Call c=IN IP4 192.168.0.222 t=0 0 m=audio 5004 RTP/AVP 0 a=sendrecv a=rtpmap:0 PCMU/8000 a=ptime:20 <-------------> [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK714aad83;rport [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 2 [ 66]: From: "Ian Stringer" ;tag=as45e056db [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 3 [ 49]: To: ;tag=d31a3c975c8e538c [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 4 [ 59]: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 5 [ 16]: CSeq: 102 INVITE [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 6 [ 37]: User-Agent: Grandstream HT486 1.0.6.7 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 7 [ 33]: Contact: [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 8 [ 64]: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 9 [ 29]: Content-Type: application/sdp [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 10 [ 19]: Supported: replaces [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 11 [ 19]: Content-Length: 157 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Header 12 [ 0]: [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 0 [ 3]: v=0 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 1 [ 37]: o=2002 8000 8000 IN IP4 192.168.0.222 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 2 [ 10]: s=SIP Call [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 3 [ 22]: c=IN IP4 192.168.0.222 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 4 [ 5]: t=0 0 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 5 [ 22]: m=audio 5004 RTP/AVP 0 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 6 [ 10]: a=sendrecv [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Body 8 [ 10]: a=ptime:20 [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: --- (12 headers 9 lines) --- [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Acked pending invite 102 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Stopping retransmission on '31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060' of Request 102: Match Found [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: SIP response 200 to standard invite [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing session-level SDP o=2002 8000 8000 IN IP4 192.168.0.222... UNSUPPORTED. [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing session-level SDP s=SIP Call... UNSUPPORTED. [Jul 25 08:59:42] DEBUG[28290] netsock2.c: Splitting '192.168.0.222' gives... [Jul 25 08:59:42] DEBUG[28290] netsock2.c: ...host '192.168.0.222' and port '(null)'. [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing session-level SDP c=IN IP4 192.168.0.222... OK. [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: Found RTP audio format 0 [Jul 25 08:59:42] DEBUG[28290] rtp_engine.c: Setting payload 0 based on m type on 0xb6d394f0 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: Found audio description format PCMU for ID 0 [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [Jul 25 08:59:42] DEBUG[28290] rtp_engine.c: Incorporating payload 0 on 0xb6d394f0 [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: Capabilities: us - 0x80030c7fffff (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|speex16|ilbc|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|siren7|siren14|testlaw|g719), peer - audio=0x4 (ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw) [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x0 (nothing), combined - 0x0 (nothing) [Jul 25 08:59:42] DEBUG[28290] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb754e6e0' [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: Peer audio RTP is at port 192.168.0.222:5004 [Jul 25 08:59:42] DEBUG[28290] rtp_engine.c: Copying payload 0 from 0xb6d394f0 to 0xb754e88c [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Peer doesn't provide T.38 UDPTL [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: We're settling with these formats: 0x4 (ulaw) [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: We have an owner, now see if we need to change this call [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Updating call counter for outgoing call [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: build_route: Contact hop: [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: list_route: hop: [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Strict routing enforced for session 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: set_destination: Parsing for address/port to send to [Jul 25 08:59:42] DEBUG[28290] netsock2.c: Splitting '192.168.0.222' gives... [Jul 25 08:59:42] DEBUG[28290] netsock2.c: ...host '192.168.0.222' and port '(null)'. [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: set_destination: set destination to 192.168.0.222:5060 [Jul 25 08:59:42] VERBOSE[28290] chan_sip.c: Transmitting (NAT) to 192.168.0.222:5060: ACK sip:2002@192.168.0.222 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK281f9e11;rport Max-Forwards: 70 From: "Ian Stringer" ;tag=as45e056db To: ;tag=d31a3c975c8e538c Contact: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 CSeq: 102 ACK User-Agent: Asterisk PBX 1.8.0-beta1 Content-Length: 0 --- [Jul 25 08:59:42] DEBUG[28290] chan_sip.c: Trying to put 'ACK sip:200' onto UDP socket destined for 192.168.0.222:5060 [Jul 25 08:59:42] DEBUG[28384] chan_dahdi.c: Requested indication 22 on channel DAHDI/1-1 [Jul 25 08:59:42] DEBUG[28265] devicestate.c: No provider found, checking channel drivers for SIP - 2002 [Jul 25 08:59:42] DEBUG[28265] chan_sip.c: Checking device state for peer 2002 [Jul 25 08:59:42] DEBUG[28265] devicestate.c: Changing state for SIP/2002 - state 1 (Not in use) [Jul 25 08:59:42] DEBUG[28265] devicestate.c: device 'SIP/2002' state '1' [Jul 25 08:59:42] DEBUG[28301] app_queue.c: Device 'SIP/2002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jul 25 08:59:42] VERBOSE[28384] app_dial.c: -- SIP/2002-00000000 answered DAHDI/1-1 [Jul 25 08:59:42] DEBUG[28384] chan_dahdi.c: Requested indication -1 on channel DAHDI/1-1 [Jul 25 08:59:42] DEBUG[28265] devicestate.c: No provider found, checking channel drivers for DAHDI - 1 [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: analog_answer 1 [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: Took DAHDI/1-1 off hook [Jul 25 08:59:42] DEBUG[28384] chan_dahdi.c: Enabled echo cancellation on channel 1 [Jul 25 08:59:42] DEBUG[28384] chan_dahdi.c: Engaged echo training on channel 1 [Jul 25 08:59:42] DEBUG[28265] devicestate.c: Changing state for DAHDI/1 - state 2 (In use) [Jul 25 08:59:42] DEBUG[28265] devicestate.c: device 'DAHDI/1' state '2' [Jul 25 08:59:42] DEBUG[28384] chan_dahdi.c: Requested indication -1 on channel DAHDI/1-1 [Jul 25 08:59:42] DEBUG[28301] app_queue.c: Device 'DAHDI/1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jul 25 08:59:42] DEBUG[28384] features.c: bridge answer set, chan answer set [Jul 25 08:59:42] DEBUG[28384] chan_dahdi.c: Requested indication 20 on channel DAHDI/1-1 [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [Jul 25 08:59:42] DEBUG[28384] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0xb754e6e0' [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Failed to get 160 samples from write factory 0xb750a1e8 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: analog_exception 1 [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: Exception on 14, channel 1 [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: __analog_handle_event 1 [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: Got event ANALOG_EVENT_POLARITY(11) on channel 1 (index 0) [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: Polarity switch detected but NOT hanging up (too close to Answer event) on channel 1, state 6 [Jul 25 08:59:42] DEBUG[28384] sig_analog.c: Polarity Reversal event occured - DEBUG 2: channel 1, state 6, pol= 1, aonp= 0, honp= 1, pdelay= 600, tv= 158 [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:42] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:43] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 was pretty quick last time, waiting for them. [Jul 25 08:59:44] DEBUG[28391] audiohook.c: Read factory 0xb75097c0 and write factory 0xb750a1e8 both fail to provide 160 samples [Jul 25 08:59:44] DEBUG[28384] sig_analog.c: analog_exception 1 [Jul 25 08:59:44] DEBUG[28384] sig_analog.c: Exception on 14, channel 1 [Jul 25 08:59:44] DEBUG[28384] sig_analog.c: __analog_handle_event 1 [Jul 25 08:59:44] DEBUG[28384] sig_analog.c: Got event ANALOG_EVENT_ONHOOK(1) on channel 1 (index 0) [Jul 25 08:59:44] DEBUG[28384] chan_dahdi.c: Disabled echo cancellation on channel 1 [Jul 25 08:59:44] DEBUG[28384] channel.c: Didn't get a frame from channel: DAHDI/1-1 [Jul 25 08:59:44] DEBUG[28384] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jul 25 08:59:44] DEBUG[28384] channel.c: Bridge stops bridging channels DAHDI/1-1 and SIP/2002-00000000 [Jul 25 08:59:44] DEBUG[28384] cdr_mysql.c: Inserting a CDR record. [Jul 25 08:59:44] DEBUG[28384] cdr_mysql.c: SQL command as follows: INSERT INTO asterisk_log (calldate,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,userfield,duration,billsec,disposition,amaflags) VALUES ('2010-07-25 08:59:37','07850709688','s','BTInbound','DAHDI/1-1','SIP/2002-00000000','Dial','SIP/2002,,trKkg','MONITOR:20100725_085940_07850709688.mp3','6.994096','2.544207','ANSWERED','3') [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is '2010-07-25 08:59:37' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is '"Ian Stringer" <07850709688>' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'BTInbound' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'DAHDI/1-1' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'SIP/2002-00000000' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'Dial' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'SIP/2002,,trKkg' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is '7' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is '3' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'ANSWERED' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'DOCUMENTATION' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is '(null)' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is '1280044777.0' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is 'MONITOR:20100725_085940_07850709688.mp3' [Jul 25 08:59:44] DEBUG[28384] pbx.c: Function result is '(null)' [Jul 25 08:59:44] DEBUG[28384] cdr_sqlite3_custom.c: About to log: INSERT INTO cdr (calldate,clid,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield,test) VALUES ('2010-07-25 08:59:37','"Ian Stringer" <07850709688>','BTInbound','DAHDI/1-1','SIP/2002-00000000','Dial','SIP/2002,,trKkg','7','3','ANSWERED','DOCUMENTATION','','1280044777.0','MONITOR:20100725_085940_07850709688.mp3','') [Jul 25 08:59:45] DEBUG[28384] channel.c: Hanging up channel 'SIP/2002-00000000' [Jul 25 08:59:45] DEBUG[28384] chan_sip.c: Hangup call SIP/2002-00000000, SIP callid 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:45] DEBUG[28384] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0xb754e6e0' [Jul 25 08:59:45] VERBOSE[28384] chan_sip.c: Scheduling destruction of SIP dialog '31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060' in 6400 ms (Method: INVITE) [Jul 25 08:59:45] DEBUG[28384] chan_sip.c: Strict routing enforced for session 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:45] VERBOSE[28384] chan_sip.c: set_destination: Parsing for address/port to send to [Jul 25 08:59:45] DEBUG[28384] netsock2.c: Splitting '192.168.0.222' gives... [Jul 25 08:59:45] DEBUG[28384] netsock2.c: ...host '192.168.0.222' and port '(null)'. [Jul 25 08:59:45] VERBOSE[28384] chan_sip.c: set_destination: set destination to 192.168.0.222:5060 [Jul 25 08:59:45] VERBOSE[28384] chan_sip.c: Reliably Transmitting (NAT) to 192.168.0.222:5060: BYE sip:2002@192.168.0.222 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK7e8ea4ba;rport Max-Forwards: 70 From: "Ian Stringer" ;tag=as45e056db To: ;tag=d31a3c975c8e538c Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 CSeq: 103 BYE User-Agent: Asterisk PBX 1.8.0-beta1 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- [Jul 25 08:59:45] DEBUG[28384] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #32 [Jul 25 08:59:45] DEBUG[28384] chan_sip.c: Trying to put 'BYE sip:200' onto UDP socket destined for 192.168.0.222:5060 [Jul 25 08:59:45] DEBUG[28265] devicestate.c: No provider found, checking channel drivers for SIP - 2002 [Jul 25 08:59:45] DEBUG[28265] chan_sip.c: Checking device state for peer 2002 [Jul 25 08:59:45] DEBUG[28265] devicestate.c: Changing state for SIP/2002 - state 1 (Not in use) [Jul 25 08:59:45] DEBUG[28265] devicestate.c: device 'SIP/2002' state '1' [Jul 25 08:59:45] DEBUG[28384] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Jul 25 08:59:45] DEBUG[28301] app_queue.c: Device 'SIP/2002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jul 25 08:59:45] DEBUG[28384] pbx.c: Spawn extension (BTInbound,s,39) exited non-zero on 'DAHDI/1-1' [Jul 25 08:59:45] VERBOSE[28384] pbx.c: == Spawn extension (BTInbound, s, 39) exited non-zero on 'DAHDI/1-1' [Jul 25 08:59:45] DEBUG[28384] channel.c: Soft-Hanging up channel 'DAHDI/1-1' [Jul 25 08:59:45] DEBUG[28384] pbx.c: Result of 'DIALSTATUS' is 'ANSWER' [Jul 25 08:59:45] DEBUG[28384] pbx.c: Launching 'NoOp' [Jul 25 08:59:45] VERBOSE[28384] pbx.c: -- Executing [h@BTInbound:1] NoOp("DAHDI/1-1", "ANSWER") in new stack [Jul 25 08:59:45] DEBUG[28384] pbx.c: Launching 'AGI' [Jul 25 08:59:45] VERBOSE[28384] pbx.c: -- Executing [h@BTInbound:2] AGI("DAHDI/1-1", "/var/scripts/asterisk/ext_clear_caller.pl") in new stack [Jul 25 08:59:45] DEBUG[28384] res_agi.c: Hungup channel detected, running agi in dead mode. [Jul 25 08:59:45] VERBOSE[28384] res_agi.c: -- Launched AGI Script /var/scripts/asterisk/ext_clear_caller.pl [Jul 25 08:59:45] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:192.168.0.222:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK7e8ea4ba;rport From: "Ian Stringer" ;tag=as45e056db To: ;tag=d31a3c975c8e538c Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 CSeq: 103 BYE User-Agent: Grandstream HT486 1.0.6.7 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Supported: replaces Content-Length: 0 <-------------> [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK7e8ea4ba;rport [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 2 [ 66]: From: "Ian Stringer" ;tag=as45e056db [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 3 [ 49]: To: ;tag=d31a3c975c8e538c [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 4 [ 59]: Call-ID: 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 5 [ 13]: CSeq: 103 BYE [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 6 [ 37]: User-Agent: Grandstream HT486 1.0.6.7 [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 7 [ 33]: Contact: [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 8 [ 64]: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 9 [ 19]: Supported: replaces [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Header 11 [ 0]: [Jul 25 08:59:45] VERBOSE[28290] chan_sip.c: --- (11 headers 0 lines) --- [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #32 [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Stopping retransmission on '31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060' of Request 103: Match Found [Jul 25 08:59:45] DEBUG[28290] chan_sip.c: Destroying SIP dialog 31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060 [Jul 25 08:59:45] VERBOSE[28290] chan_sip.c: Really destroying SIP dialog '31f4eb6b27a6b0857ffdda4339d26293@192.168.0.55:5060' Method: INVITE [Jul 25 08:59:45] DEBUG[28290] rtp_engine.c: Destroyed RTP instance '0xb754e6e0' [Jul 25 08:59:45] VERBOSE[28384] res_agi.c: -- AGI Script /var/scripts/asterisk/ext_clear_caller.pl completed, returning 0 [Jul 25 08:59:45] DEBUG[28384] pbx.c: Result of 'DIALSTATUS' is 'ANSWER' [Jul 25 08:59:45] DEBUG[28384] pbx.c: Expression result is '0' [Jul 25 08:59:45] DEBUG[28384] pbx.c: Launching 'GotoIf' [Jul 25 08:59:45] VERBOSE[28384] pbx.c: -- Executing [h@BTInbound:3] GotoIf("DAHDI/1-1", "0?MNoAnswer") in new stack [Jul 25 08:59:45] DEBUG[28384] pbx.c: Not taking any branch [Jul 25 08:59:45] DEBUG[28384] pbx.c: Launching 'Hangup' [Jul 25 08:59:45] VERBOSE[28384] pbx.c: -- Executing [h@BTInbound:4] Hangup("DAHDI/1-1", "") in new stack [Jul 25 08:59:45] DEBUG[28384] pbx.c: Spawn extension (BTInbound,h,4) exited non-zero on 'DAHDI/1-1' [Jul 25 08:59:45] VERBOSE[28384] pbx.c: == Spawn extension (BTInbound, h, 4) exited non-zero on 'DAHDI/1-1' [Jul 25 08:59:45] DEBUG[28384] channel.c: Hanging up channel 'DAHDI/1-1' [Jul 25 08:59:45] DEBUG[28384] chan_dahdi.c: dahdi_hangup(DAHDI/1-1) [Jul 25 08:59:45] DEBUG[28384] sig_analog.c: analog_hangup 1 [Jul 25 08:59:45] DEBUG[28384] sig_analog.c: Hangup: channel: 1 index = 0, normal = 1, callwait = 0, thirdcall = 0 [Jul 25 08:59:45] DEBUG[28384] chan_dahdi.c: Set option TONE VERIFY, mode: OFF(0) on DAHDI/1-1 [Jul 25 08:59:45] DEBUG[28384] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/1-1 [Jul 25 08:59:45] DEBUG[28384] sig_analog.c: Updated conferencing on 1, with 0 conference users [Jul 25 08:59:45] VERBOSE[28384] sig_analog.c: -- Hanging up on 'DAHDI/1-1' [Jul 25 08:59:45] VERBOSE[28384] chan_dahdi.c: -- Hungup 'DAHDI/1-1' [Jul 25 08:59:45] DEBUG[28391] autochan.c: Removed autochan 0xb7501718 from the list, about to free it [Jul 25 08:59:45] VERBOSE[28391] app_mixmonitor.c: == MixMonitor close filestream [Jul 25 08:59:45] VERBOSE[28391] app_mixmonitor.c: == Executing [/usr/local/bin/lame --preset standard --silent /var/spool/asterisk/monitor/20100725_085940_07850709688.wav /var/spool/asterisk/monitor/20100725_085940_07850709688.mp3 && rm -f /var/spool/asterisk/monitor/20100725_085940_07850709688.wav] [Jul 25 08:59:45] DEBUG[28265] devicestate.c: No provider found, checking channel drivers for DAHDI - 1 [Jul 25 08:59:45] DEBUG[28265] devicestate.c: Changing state for DAHDI/1 - state 0 (Unknown) [Jul 25 08:59:45] DEBUG[28265] devicestate.c: device 'DAHDI/1' state '0' [Jul 25 08:59:45] DEBUG[28301] app_queue.c: Device 'DAHDI/1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Jul 25 08:59:45] VERBOSE[28391] app_mixmonitor.c: == End MixMonitor Recording DAHDI/1-1 [Jul 25 08:59:54] NOTICE[28290] chan_sip.c: -- Re-registration for 8248957@sip2.plus.net [Jul 25 08:59:54] VERBOSE[28290] dnsmgr.c: > doing dnsmgr_lookup for 'sip2.plus.net' [Jul 25 08:59:54] VERBOSE[28290] srv.c: > ast_get_srv: SRV lookup for '_sip._UDP.sip2.plus.net' mapped to host v-sip-floating-2.gradwell.net, port 5060 [Jul 25 08:59:54] DEBUG[28290] netsock2.c: Splitting 'v-sip-floating-2.gradwell.net' gives... [Jul 25 08:59:54] DEBUG[28290] netsock2.c: ...host 'v-sip-floating-2.gradwell.net' and port '(null)'. [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Allocating new SIP dialog for 664514495c3432f9566b08350bb4eb21@127.0.0.1 - REGISTER (No RTP) [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: SIP Registry sip2.plus.net: refcount now 3 [Jul 25 08:59:54] DEBUG[28290] acl.c: For destination '79.135.125.154', our source address is '192.168.0.55'. [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Target address 79.135.125.154:5060 is not local, substituting externaddr [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 80.229.198.195:5060 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: SIP Registry sip2.plus.net: refcount now 4 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Scheduled a registration timeout for sip2.plus.net id #33 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: >>> Re-using Auth data for 8248957@sip2.plus.net [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Initializing initreq for method REGISTER - callid 664514495c3432f9566b08350bb4eb21@127.0.0.1 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 0 [ 34]: REGISTER sip:sip2.plus.net SIP/2.0 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK27a7d483;rport [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 3 [ 48]: From: ;tag=as79cdfa70 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 4 [ 31]: To: [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 5 [ 51]: Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 6 [ 18]: CSeq: 104 REGISTER [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 7 [ 36]: User-Agent: Asterisk PBX 1.8.0-beta1 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 8 [241]: Authorization: Digest username="8248957", realm="newsip", algorithm=MD5, uri="sip:sip2.plus.net", nonce="4c4beeaf000142edc38cd38bacccc12a0168c58cd7921cbf", response="75bae08ce45cfecf62d78247b3979f36", qop=auth, cnonce="6626143a", nc=00000002 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 9 [ 12]: Expires: 120 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 10 [ 42]: Contact: [Jul 25 08:59:54] VERBOSE[28290] chan_sip.c: REGISTER 11 headers, 0 lines [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: REGISTER attempt 1 to 8248957@sip2.plus.net [Jul 25 08:59:54] VERBOSE[28290] chan_sip.c: Reliably Transmitting (NAT) to 79.135.125.154:5060: REGISTER sip:sip2.plus.net SIP/2.0 Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK27a7d483;rport Max-Forwards: 70 From: ;tag=as79cdfa70 To: Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 CSeq: 104 REGISTER User-Agent: Asterisk PBX 1.8.0-beta1 Authorization: Digest username="8248957", realm="newsip", algorithm=MD5, uri="sip:sip2.plus.net", nonce="4c4beeaf000142edc38cd38bacccc12a0168c58cd7921cbf", response="75bae08ce45cfecf62d78247b3979f36", qop=auth, cnonce="6626143a", nc=00000002 Expires: 120 Contact: Content-Length: 0 --- [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #34 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 79.135.125.154:5060 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: SIP Registry sip2.plus.net: refcount now 3 [Jul 25 08:59:54] NOTICE[28290] chan_sip.c: -- Re-registration for 6478177@sipgate.co.uk [Jul 25 08:59:54] VERBOSE[28290] dnsmgr.c: > doing dnsmgr_lookup for 'sipgate.co.uk' [Jul 25 08:59:54] VERBOSE[28290] srv.c: > ast_get_srv: SRV lookup for '_sip._UDP.sipgate.co.uk' mapped to host sipgate.co.uk, port 5060 [Jul 25 08:59:54] DEBUG[28290] netsock2.c: Splitting 'sipgate.co.uk' gives... [Jul 25 08:59:54] DEBUG[28290] netsock2.c: ...host 'sipgate.co.uk' and port '(null)'. [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Allocating new SIP dialog for 385d2bd34800c8435b0fdffc23ac2021@127.0.0.1 - REGISTER (No RTP) [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: SIP Registry sipgate.co.uk: refcount now 3 [Jul 25 08:59:54] DEBUG[28290] acl.c: For destination '217.10.79.23', our source address is '192.168.0.55'. [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Target address 217.10.79.23:5060 is not local, substituting externaddr [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 80.229.198.195:5060 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: SIP Registry sipgate.co.uk: refcount now 4 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Scheduled a registration timeout for sipgate.co.uk id #35 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: >>> Re-using Auth data for 6478177@sipgate.co.uk [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Initializing initreq for method REGISTER - callid 385d2bd34800c8435b0fdffc23ac2021@127.0.0.1 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 0 [ 34]: REGISTER sip:sipgate.co.uk SIP/2.0 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK56c3e14d;rport [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 3 [ 48]: From: ;tag=as5063cc74 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 4 [ 31]: To: [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 5 [ 51]: Call-ID: 385d2bd34800c8435b0fdffc23ac2021@127.0.0.1 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 6 [ 18]: CSeq: 104 REGISTER [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 7 [ 36]: User-Agent: Asterisk PBX 1.8.0-beta1 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 8 [198]: Authorization: Digest username="6478177", realm="sipgate.co.uk", algorithm=MD5, uri="sip:sipgate.co.uk", nonce="4c4befbc173fdf3b01c781fb02a1e162e99a3c3a", response="f636be22f6331d346bf488a3684c118b" [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 9 [ 12]: Expires: 120 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 10 [ 42]: Contact: [Jul 25 08:59:54] VERBOSE[28290] chan_sip.c: REGISTER 11 headers, 0 lines [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: REGISTER attempt 1 to 6478177@sipgate.co.uk [Jul 25 08:59:54] VERBOSE[28290] chan_sip.c: Reliably Transmitting (NAT) to 217.10.79.23:5060: REGISTER sip:sipgate.co.uk SIP/2.0 Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK56c3e14d;rport Max-Forwards: 70 From: ;tag=as5063cc74 To: Call-ID: 385d2bd34800c8435b0fdffc23ac2021@127.0.0.1 CSeq: 104 REGISTER User-Agent: Asterisk PBX 1.8.0-beta1 Authorization: Digest username="6478177", realm="sipgate.co.uk", algorithm=MD5, uri="sip:sipgate.co.uk", nonce="4c4befbc173fdf3b01c781fb02a1e162e99a3c3a", response="f636be22f6331d346bf488a3684c118b" Expires: 120 Contact: Content-Length: 0 --- [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #36 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 217.10.79.23:5060 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: SIP Registry sipgate.co.uk: refcount now 3 [Jul 25 08:59:54] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:79.135.125.154:5060 ---> SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK27a7d483;rport=5060 From: ;tag=as79cdfa70 To: ;tag=14594fa2c5ed7a6c6e0806b484dcbe6b-3b1d Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 CSeq: 104 REGISTER Proxy-Authenticate: Digest realm="newsip", nonce="4c4bef18000156025c455bc7fb81df57d38a0e3a8bf25f64", qop="auth" Server: OpenSIPS (1.6.1-notls (i386/linux)) Content-Length: 0 <-------------> [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 0 [ 41]: SIP/2.0 407 Proxy Authentication Required [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 1 [ 70]: Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK27a7d483;rport=5060 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 2 [ 48]: From: ;tag=as79cdfa70 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 3 [ 73]: To: ;tag=14594fa2c5ed7a6c6e0806b484dcbe6b-3b1d [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 4 [ 51]: Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 5 [ 18]: CSeq: 104 REGISTER [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 6 [111]: Proxy-Authenticate: Digest realm="newsip", nonce="4c4bef18000156025c455bc7fb81df57d38a0e3a8bf25f64", qop="auth" [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 7 [ 43]: Server: OpenSIPS (1.6.1-notls (i386/linux)) [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 8 [ 17]: Content-Length: 0 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Header 9 [ 0]: [Jul 25 08:59:54] VERBOSE[28290] chan_sip.c: --- (9 headers 0 lines) --- [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #34 [Jul 25 08:59:54] DEBUG[28290] chan_sip.c: Stopping retransmission on '664514495c3432f9566b08350bb4eb21@127.0.0.1' of Request 104: Match Found [Jul 25 08:59:54] VERBOSE[28290] chan_sip.c: Responding to challenge, registration to domain/host name sip2.plus.net [Jul 25 08:59:54] VERBOSE[28290] dnsmgr.c: > doing dnsmgr_lookup for 'sip2.plus.net' [Jul 25 08:59:54] VERBOSE[28290] srv.c: > ast_get_srv: SRV lookup for '_sip._UDP.sip2.plus.net' mapped to host v-sip-floating-1.gradwell.net, port 5060 [Jul 25 08:59:54] DEBUG[28290] netsock2.c: Splitting 'v-sip-floating-1.gradwell.net' gives... [Jul 25 08:59:54] DEBUG[28290] netsock2.c: ...host 'v-sip-floating-1.gradwell.net' and port '(null)'. [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Initializing already initialized SIP dialog 664514495c3432f9566b08350bb4eb21@127.0.0.1 (presumably reinvite) [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 0 [ 34]: REGISTER sip:sip2.plus.net SIP/2.0 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK6a97a7cd;rport [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 3 [ 48]: From: ;tag=as06334438 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 4 [ 31]: To: [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 5 [ 51]: Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 6 [ 18]: CSeq: 105 REGISTER [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 7 [ 36]: User-Agent: Asterisk PBX 1.8.0-beta1 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 8 [247]: Proxy-Authorization: Digest username="8248957", realm="newsip", algorithm=MD5, uri="sip:sip2.plus.net", nonce="4c4bef18000156025c455bc7fb81df57d38a0e3a8bf25f64", response="edaf5cce142f62d76119143226339edf", qop=auth, cnonce="5106c7ae", nc=00000001 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 9 [ 12]: Expires: 120 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 10 [ 42]: Contact: [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: REGISTER 11 headers, 0 lines [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: REGISTER attempt 2 to 8248957@sip2.plus.net [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: Reliably Transmitting (NAT) to 79.135.125.154:5060: REGISTER sip:sip2.plus.net SIP/2.0 Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK6a97a7cd;rport Max-Forwards: 70 From: ;tag=as06334438 To: Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 CSeq: 105 REGISTER User-Agent: Asterisk PBX 1.8.0-beta1 Proxy-Authorization: Digest username="8248957", realm="newsip", algorithm=MD5, uri="sip:sip2.plus.net", nonce="4c4bef18000156025c455bc7fb81df57d38a0e3a8bf25f64", response="edaf5cce142f62d76119143226339edf", qop=auth, cnonce="5106c7ae", nc=00000001 Expires: 120 Contact: Content-Length: 0 --- [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #37 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Trying to put 'REGISTER si' onto UDP socket destined for 79.135.125.154:5060 [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:217.10.79.23:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK56c3e14d;rport=5060 From: ;tag=as5063cc74 To: ;tag=8367f0f887e3954243ec30fa0f5db288.d3a6 Call-ID: 385d2bd34800c8435b0fdffc23ac2021@127.0.0.1 CSeq: 104 REGISTER Contact: ;expires=120 Content-Length: 0 <-------------> [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 1 [ 70]: Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK56c3e14d;rport=5060 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 2 [ 48]: From: ;tag=as5063cc74 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 3 [ 73]: To: ;tag=8367f0f887e3954243ec30fa0f5db288.d3a6 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 4 [ 51]: Call-ID: 385d2bd34800c8435b0fdffc23ac2021@127.0.0.1 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 5 [ 18]: CSeq: 104 REGISTER [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 6 [ 54]: Contact: ;expires=120 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 8 [ 0]: [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: --- (8 headers 0 lines) --- [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #36 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Stopping retransmission on '385d2bd34800c8435b0fdffc23ac2021@127.0.0.1' of Request 104: Match Found [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Registration successful [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Cancelling timeout 35 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: SIP Registry sipgate.co.uk: refcount now 2 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: SIP Registry sipgate.co.uk: refcount now 1 [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: Scheduling destruction of SIP dialog '385d2bd34800c8435b0fdffc23ac2021@127.0.0.1' in 32000 ms (Method: REGISTER) [Jul 25 08:59:55] NOTICE[28290] chan_sip.c: Outbound Registration: Expiry for sipgate.co.uk is 120 sec (Scheduling reregistration in 105 s) [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: SIP Registry sipgate.co.uk: refcount now 2 [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:79.135.125.154:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK6a97a7cd;rport=5060 From: ;tag=as06334438 To: ;tag=14594fa2c5ed7a6c6e0806b484dcbe6b-00c1 Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 CSeq: 105 REGISTER Contact: ;expires=120 Server: OpenSIPS (1.6.1-notls (i386/linux)) Content-Length: 0 <-------------> [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 1 [ 70]: Via: SIP/2.0/UDP 80.229.198.195:5060;branch=z9hG4bK6a97a7cd;rport=5060 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 2 [ 48]: From: ;tag=as06334438 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 3 [ 73]: To: ;tag=14594fa2c5ed7a6c6e0806b484dcbe6b-00c1 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 4 [ 51]: Call-ID: 664514495c3432f9566b08350bb4eb21@127.0.0.1 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 5 [ 18]: CSeq: 105 REGISTER [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 6 [ 54]: Contact: ;expires=120 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 7 [ 43]: Server: OpenSIPS (1.6.1-notls (i386/linux)) [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 8 [ 17]: Content-Length: 0 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Header 9 [ 0]: [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: --- (9 headers 0 lines) --- [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #37 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Stopping retransmission on '664514495c3432f9566b08350bb4eb21@127.0.0.1' of Request 105: Match Found [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Registration successful [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: Cancelling timeout 33 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: SIP Registry sip2.plus.net: refcount now 2 [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: SIP Registry sip2.plus.net: refcount now 1 [Jul 25 08:59:55] VERBOSE[28290] chan_sip.c: Scheduling destruction of SIP dialog '664514495c3432f9566b08350bb4eb21@127.0.0.1' in 32000 ms (Method: REGISTER) [Jul 25 08:59:55] NOTICE[28290] chan_sip.c: Outbound Registration: Expiry for sip2.plus.net is 120 sec (Scheduling reregistration in 105 s) [Jul 25 08:59:55] DEBUG[28290] chan_sip.c: SIP Registry sip2.plus.net: refcount now 2 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Allocating new SIP dialog for 6e9ead8365ee36377bdd331b08a86e4b@127.0.0.1:0 - OPTIONS (No RTP) [Jul 25 09:00:09] DEBUG[28290] acl.c: For destination '192.168.0.222', our source address is '192.168.0.55'. [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.0.55:5060 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Initializing initreq for method OPTIONS - callid 79cd2310480b16e670ca424134751c47@192.168.0.55:5060 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 0 [ 38]: OPTIONS sip:2002@192.168.0.222 SIP/2.0 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK1407454c;rport [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 3 [ 59]: From: "asterisk" ;tag=as7b831a71 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 4 [ 28]: To: [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 5 [ 41]: Contact: [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 6 [ 59]: Call-ID: 79cd2310480b16e670ca424134751c47@192.168.0.55:5060 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 8 [ 36]: User-Agent: Asterisk PBX 1.8.0-beta1 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 9 [ 35]: Date: Sun, 25 Jul 2010 08:00:09 GMT [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jul 25 09:00:09] VERBOSE[28290] chan_sip.c: Reliably Transmitting (NAT) to 192.168.0.222:5060: OPTIONS sip:2002@192.168.0.222 SIP/2.0 Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK1407454c;rport Max-Forwards: 70 From: "asterisk" ;tag=as7b831a71 To: Contact: Call-ID: 79cd2310480b16e670ca424134751c47@192.168.0.55:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.8.0-beta1 Date: Sun, 25 Jul 2010 08:00:09 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 --- [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #42 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.0.222:5060 [Jul 25 09:00:09] VERBOSE[28290] chan_sip.c: <--- SIP read from UDP:192.168.0.222:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK1407454c;rport From: "asterisk" ;tag=as7b831a71 To: ;tag=47ea0369d90a307a Call-ID: 79cd2310480b16e670ca424134751c47@192.168.0.55:5060 CSeq: 102 OPTIONS User-Agent: Grandstream HT486 1.0.6.7 Contact: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE Supported: replaces Content-Length: 0 <-------------> [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.55:5060;branch=z9hG4bK1407454c;rport [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 2 [ 59]: From: "asterisk" ;tag=as7b831a71 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 3 [ 49]: To: ;tag=47ea0369d90a307a [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 4 [ 59]: Call-ID: 79cd2310480b16e670ca424134751c47@192.168.0.55:5060 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 6 [ 37]: User-Agent: Grandstream HT486 1.0.6.7 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 7 [ 33]: Contact: [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 8 [ 64]: Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 9 [ 19]: Supported: replaces [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Header 11 [ 0]: [Jul 25 09:00:09] VERBOSE[28290] chan_sip.c: --- (11 headers 0 lines) --- [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #42 [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Stopping retransmission on '79cd2310480b16e670ca424134751c47@192.168.0.55:5060' of Request 102: Match Found [Jul 25 09:00:09] DEBUG[28290] chan_sip.c: Destroying SIP dialog 79cd2310480b16e670ca424134751c47@192.168.0.55:5060 [Jul 25 09:00:09] VERBOSE[28290] chan_sip.c: Really destroying SIP dialog '79cd2310480b16e670ca424134751c47@192.168.0.55:5060' Method: OPTIONS [Jul 25 09:00:27] VERBOSE[28290] chan_sip.c: Really destroying SIP dialog '385d2bd34800c8435b0fdffc23ac2021@127.0.0.1' Method: REGISTER [Jul 25 09:00:27] VERBOSE[28290] chan_sip.c: Really destroying SIP dialog '664514495c3432f9566b08350bb4eb21@127.0.0.1' Method: REGISTER