[2012-03-07 20:07:03.144] VERBOSE[29378] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE [2012-03-07 20:07:03.144] VERBOSE[29378] chan_iax2.c: Timestamp: 00003ms SCall: 06096 DCall: 00000 [10.xxx.xxx.xxx:4569] [2012-03-07 20:07:03.144] VERBOSE[29378] chan_iax2.c: [2012-03-07 20:07:03.160] VERBOSE[29379] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK [2012-03-07 20:07:03.160] VERBOSE[29379] chan_iax2.c: Timestamp: 00003ms SCall: 06096 DCall: 00001 [10.xxx.xxx.xxx:4569] [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: Timestamp: 00004ms SCall: 17440 DCall: 00000 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: VERSION : 2 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CALLED NUMBER : 5xxxx7 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CODEC_PREFS : (alaw) [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CALLING NUMBER : 01xxxxxxxx5 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CALLING PRESNTN : 0 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CALLING TYPEOFN : 0 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CALLING TRANSIT : 0 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CALLING NAME : [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: LANGUAGE : en [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: USERNAME : trunk [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: FORMAT : 8 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: FORMAT2 : alaw [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CAPABILITY : 8 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: CAPABILITY2 : alaw [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: ADSICPE : 2 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: DATE TIME : 2012-03-07 20:07:06 [2012-03-07 20:07:06.039] VERBOSE[29380] chan_iax2.c: [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: Timestamp: 00038ms SCall: 17440 DCall: 00000 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: VERSION : 2 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CALLED NUMBER : 5xxxx7 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CODEC_PREFS : (alaw) [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CALLING NUMBER : 01xxxxxxxx5 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CALLING PRESNTN : 0 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CALLING TYPEOFN : 0 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CALLING TRANSIT : 0 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CALLING NAME : [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: LANGUAGE : en [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: USERNAME : trunk [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: FORMAT : 8 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: FORMAT2 : alaw [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CAPABILITY : 8 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CAPABILITY2 : alaw [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: ADSICPE : 2 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: DATE TIME : 2012-03-07 20:07:06 [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: CALLTOKEN : 51 bytes [2012-03-07 20:07:06.062] VERBOSE[29381] chan_iax2.c: [2012-03-07 20:07:06.062] DEBUG[29381] chan_iax2.c: ip callno count incremented to 2 for 10.xxx.xxx.xx2 [2012-03-07 20:07:06.062] DEBUG[29381] chan_iax2.c: New max nontrunk callno is 1708 [2012-03-07 20:07:06.062] DEBUG[29381] chan_iax2.c: Creating new call structure 1707 [2012-03-07 20:07:06.062] DEBUG[29381] chan_iax2.c: Received packet 0, (6, 1) [2012-03-07 20:07:06.062] DEBUG[29381] chan_iax2.c: IAX subclass 1 received [2012-03-07 20:07:06.062] DEBUG[29381] chan_iax2.c: For call=1707, set last=38 [2012-03-07 20:07:06.063] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ [2012-03-07 20:07:06.063] VERBOSE[29372] chan_iax2.c: Timestamp: 00003ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.063] VERBOSE[29372] chan_iax2.c: AUTHMETHODS : 3 [2012-03-07 20:07:06.063] VERBOSE[29372] chan_iax2.c: CHALLENGE : [2012-03-07 20:07:06.063] VERBOSE[29372] chan_iax2.c: USERNAME : trunk [2012-03-07 20:07:06.063] VERBOSE[29372] chan_iax2.c: [2012-03-07 20:07:06.084] VERBOSE[29382] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP [2012-03-07 20:07:06.084] VERBOSE[29382] chan_iax2.c: Timestamp: 00061ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.084] VERBOSE[29382] chan_iax2.c: MD5 RESULT : f5915926c78c75f5f492bd1556d77fff [2012-03-07 20:07:06.084] VERBOSE[29382] chan_iax2.c: [2012-03-07 20:07:06.084] DEBUG[29382] chan_iax2.c: Received packet 1, (6, 9) [2012-03-07 20:07:06.084] DEBUG[29382] chan_iax2.c: Cancelling transmission of packet 0 [2012-03-07 20:07:06.084] DEBUG[29382] chan_iax2.c: IAX subclass 9 received [2012-03-07 20:07:06.084] DEBUG[29382] chan_iax2.c: For call=1707, set last=61 [2012-03-07 20:07:06.084] VERBOSE[29382] chan_iax2.c: -- Accepting AUTHENTICATED call from 10.xxx.xxx.xx2: [2012-03-07 20:07:06.084] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT [2012-03-07 20:07:06.084] VERBOSE[29372] chan_iax2.c: Timestamp: 00024ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.084] VERBOSE[29372] chan_iax2.c: FORMAT : 8 [2012-03-07 20:07:06.084] VERBOSE[29372] chan_iax2.c: FORMAT2 : alaw [2012-03-07 20:07:06.084] VERBOSE[29372] chan_iax2.c: [2012-03-07 20:07:06.084] DEBUG[29403] pbx.c: Launching 'NoOp' [2012-03-07 20:07:06.084] VERBOSE[29403] pbx.c: -- Executing [5xxxx7@int:1] NoOp("IAX2/trunk-1707", "--- 01xxxxxxxx5 calling on oh-trunk 5xxxx7 ---") in new stack [2012-03-07 20:07:06.084] DEBUG[29403] pbx.c: Launching 'Set' [2012-03-07 20:07:06.084] VERBOSE[29403] pbx.c: -- Executing [5xxxx7@int:2] Set("IAX2/trunk-1707", "trunkname=INTTRUNKPRIV") in new stack [2012-03-07 20:07:06.084] DEBUG[29403] pbx.c: Launching 'Set' [2012-03-07 20:07:06.084] VERBOSE[29403] pbx.c: -- Executing [5xxxx7@int:3] Set("IAX2/trunk-1707", "CALLERID(all)=<901xxxxxxxx5>") in new stack [2012-03-07 20:07:06.084] DEBUG[29403] pbx.c: Launching 'Goto' [2012-03-07 20:07:06.084] VERBOSE[29403] pbx.c: -- Executing [5xxxx7@int:4] Goto("IAX2/trunk-1707", "out-to-pbx,5xxxx7,1") in new stack [2012-03-07 20:07:06.084] VERBOSE[29403] pbx.c: -- Goto (out-to-pbx,5xxxx7,1) [2012-03-07 20:07:06.084] DEBUG[29403] pbx.c: Launching 'Gosub' [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Executing [5xxxx7@out-to-pbx:1] Gosub("IAX2/trunk-1707", "dialpbx,s,1(5xxxx7,INTTRUNKPRIV,xx@xx.xx)") in new stack [2012-03-07 20:07:06.085] DEBUG[29403] app_stack.c: Channel IAX2/trunk-1707 has no datastore, so we're allocating one. [2012-03-07 20:07:06.085] DEBUG[29403] app_stack.c: Setting 'ARG1' to '5xxxx7' [2012-03-07 20:07:06.085] DEBUG[29403] app_stack.c: Setting 'ARG2' to 'INTTRUNKPRIV' [2012-03-07 20:07:06.085] DEBUG[29403] app_stack.c: Setting 'ARG3' to 'xx@xx.xx' [2012-03-07 20:07:06.085] DEBUG[29403] func_strings.c: Allowed: 0123456789 [2012-03-07 20:07:06.085] DEBUG[29403] pbx.c: Launching 'Set' [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Executing [s@dialpbx:1] Set("IAX2/trunk-1707", "CALLERID(num)=901xxxxxxxx5") in new stack [2012-03-07 20:07:06.085] DEBUG[29403] pbx.c: Launching 'Set' [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Executing [s@dialpbx:2] Set("IAX2/trunk-1707", "DB(lastcaller/5xxxx7)=901xxxxxxxx5") in new stack [2012-03-07 20:07:06.085] DEBUG[29403] pbx.c: Launching 'Set' [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Executing [s@dialpbx:3] Set("IAX2/trunk-1707", "DB(lastcallertime/5xxxx7)=1331150826") in new stack [2012-03-07 20:07:06.085] DEBUG[29403] pbx.c: Launching 'Set' [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Executing [s@dialpbx:4] Set("IAX2/trunk-1707", "ExtenEmail=xx@xx.xx") in new stack [2012-03-07 20:07:06.085] DEBUG[29403] pbx.c: Expression result is '0' [2012-03-07 20:07:06.085] DEBUG[29403] pbx.c: Launching 'GotoIf' [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Executing [s@dialpbx:5] GotoIf("IAX2/trunk-1707", "0?9:6") in new stack [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Goto (dialpbx,s,6) [2012-03-07 20:07:06.085] DEBUG[29403] pbx.c: Launching 'Dial' [2012-03-07 20:07:06.085] VERBOSE[29403] pbx.c: -- Executing [s@dialpbx:6] Dial("IAX2/trunk-1707", "DAHDI/G3/5xxxx7,16") in new stack [2012-03-07 20:07:06.085] DEBUG[29403] chan_dahdi.c: Using channel 62 [2012-03-07 20:07:06.085] DEBUG[29403] sig_pri.c: sig_pri_request 62 [2012-03-07 20:07:06.085] DEBUG[29403] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [2012-03-07 20:07:06.085] DEBUG[29403] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [2012-03-07 20:07:06.086] DEBUG[29403] rtp_engine.c: Can't find native functions for channel 'DAHDI/i2/5xxxx7-1' [2012-03-07 20:07:06.086] DEBUG[29403] rtp_engine.c: Seeded SDP of 'DAHDI/i2/5xxxx7-1' with that of 'IAX2/trunk-1707' [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable DIALEDTIME. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable ANSWEREDTIME. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable DIALEDPEERNAME. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable DIALEDPEERNUMBER. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable DIALSTATUS. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable ExtenEmail. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable ARGC. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable ARG3. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable ARG2. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable ARG1. [2012-03-07 20:07:06.086] DEBUG[29403] channel.c: Not copying variable trunkname. [2012-03-07 20:07:06.086] DEBUG[29403] sig_pri.c: CALLER NAME: NUM: 901xxxxxxxx5 [2012-03-07 20:07:06.086] VERBOSE[29403] sig_pri.c: -- Requested transfer capability: 0x00 - SPEECH [2012-03-07 20:07:06.091] VERBOSE[29403] app_dial.c: -- Called DAHDI/G3/5xxxx7 [2012-03-07 20:07:06.104] VERBOSE[29383] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK [2012-03-07 20:07:06.104] VERBOSE[29383] chan_iax2.c: Timestamp: 00024ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.104] DEBUG[29383] chan_iax2.c: Received packet 2, (6, 4) [2012-03-07 20:07:06.104] DEBUG[29383] chan_iax2.c: Cancelling transmission of packet 1 [2012-03-07 20:07:06.104] DEBUG[29383] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.144] DEBUG[29386] sig_pri.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/31 span 2 [2012-03-07 20:07:06.144] VERBOSE[29403] app_dial.c: -- DAHDI/i2/5xxxx7-1 is proceeding passing it to IAX2/trunk-1707 [2012-03-07 20:07:06.144] DEBUG[29403] chan_iax2.c: Indicating condition 15 [2012-03-07 20:07:06.144] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: PROCDNG [2012-03-07 20:07:06.144] VERBOSE[29372] chan_iax2.c: Timestamp: 00027ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.145] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 002 Type: VOICE Subclass: 8 [2012-03-07 20:07:06.145] VERBOSE[29372] chan_iax2.c: Timestamp: 00100ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.165] VERBOSE[29374] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK [2012-03-07 20:07:06.165] VERBOSE[29374] chan_iax2.c: Timestamp: 00027ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.165] DEBUG[29374] chan_iax2.c: Received packet 2, (6, 4) [2012-03-07 20:07:06.165] DEBUG[29374] chan_iax2.c: Cancelling transmission of packet 2 [2012-03-07 20:07:06.165] DEBUG[29374] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.165] DEBUG[29403] chan_iax2.c: Created trunk peer for '10.xxx.xxx.xx2:4569' [2012-03-07 20:07:06.165] DEBUG[29403] chan_iax2.c: Expanded trunk '10.xxx.xxx.xx2:4569' to 6400 bytes [2012-03-07 20:07:06.166] VERBOSE[29375] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK [2012-03-07 20:07:06.166] VERBOSE[29375] chan_iax2.c: Timestamp: 00100ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.166] DEBUG[29375] chan_iax2.c: Received packet 2, (6, 4) [2012-03-07 20:07:06.166] DEBUG[29375] chan_iax2.c: Cancelling transmission of packet 3 [2012-03-07 20:07:06.166] DEBUG[29375] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.273] VERBOSE[29376] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 004 Type: VOICE Subclass: 8 [2012-03-07 20:07:06.273] VERBOSE[29376] chan_iax2.c: Timestamp: 00249ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.273] DEBUG[29376] chan_iax2.c: Received packet 2, (2, 8) [2012-03-07 20:07:06.273] DEBUG[29376] chan_iax2.c: Ooh, voice format changed to 'alaw' [2012-03-07 20:07:06.273] VERBOSE[29376] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 004 ISeqno: 003 Type: IAX Subclass: ACK [2012-03-07 20:07:06.273] VERBOSE[29376] chan_iax2.c: Timestamp: 00249ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.273] DEBUG[29376] chan_iax2.c: calc_rxstamp: call=1707: rxcore set to 1331150826.273472 - 249ms [2012-03-07 20:07:06.273] DEBUG[29376] chan_iax2.c: calc_rxstamp: call=1707: works out as 1331150826.024472 [2012-03-07 20:07:06.273] DEBUG[29376] chan_iax2.c: For call=1707, set last=249 [2012-03-07 20:07:06.312] DEBUG[29386] chan_dahdi.c: Enabled echo cancellation on channel 62 [2012-03-07 20:07:06.313] VERBOSE[29403] app_dial.c: -- DAHDI/i2/5xxxx7-1 is ringing [2012-03-07 20:07:06.313] DEBUG[29403] chan_iax2.c: Indicating condition 3 [2012-03-07 20:07:06.313] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 003 Type: CONTROL Subclass: RINGING [2012-03-07 20:07:06.313] VERBOSE[29372] chan_iax2.c: Timestamp: 00263ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.313] VERBOSE[29403] app_dial.c: -- DAHDI/i2/5xxxx7-1 is making progress passing it to IAX2/trunk-1707 [2012-03-07 20:07:06.313] DEBUG[29403] chan_iax2.c: Indicating condition 14 [2012-03-07 20:07:06.313] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 003 Type: CONTROL Subclass: PROGRES [2012-03-07 20:07:06.313] VERBOSE[29372] chan_iax2.c: Timestamp: 00266ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.333] VERBOSE[29379] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK [2012-03-07 20:07:06.333] VERBOSE[29379] chan_iax2.c: Timestamp: 00263ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.333] DEBUG[29379] chan_iax2.c: Received packet 3, (6, 4) [2012-03-07 20:07:06.333] DEBUG[29379] chan_iax2.c: Cancelling transmission of packet 4 [2012-03-07 20:07:06.333] DEBUG[29379] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.335] VERBOSE[29380] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 006 Type: IAX Subclass: ACK [2012-03-07 20:07:06.335] VERBOSE[29380] chan_iax2.c: Timestamp: 00266ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.335] DEBUG[29380] chan_iax2.c: Received packet 3, (6, 4) [2012-03-07 20:07:06.335] DEBUG[29380] chan_iax2.c: Cancelling transmission of packet 5 [2012-03-07 20:07:06.335] DEBUG[29380] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.342] DEBUG[29381] jitterbuf.c: head 259, tail 259, ts 279 [2012-03-07 20:07:06.342] DEBUG[29381] jitterbuf.c: head 259, tail 279, ts 299 [2012-03-07 20:07:06.389] DEBUG[29375] jitterbuf.c: head 279, tail 299, ts 319 [2012-03-07 20:07:06.389] DEBUG[29375] jitterbuf.c: head 279, tail 319, ts 339 [2012-03-07 20:07:06.441] DEBUG[29379] jitterbuf.c: head 319, tail 339, ts 359 [2012-03-07 20:07:06.441] DEBUG[29379] jitterbuf.c: head 319, tail 359, ts 379 [2012-03-07 20:07:06.441] DEBUG[29379] jitterbuf.c: head 319, tail 379, ts 399 [2012-03-07 20:07:06.489] DEBUG[29383] jitterbuf.c: head 379, tail 399, ts 419 [2012-03-07 20:07:06.489] DEBUG[29383] jitterbuf.c: head 379, tail 419, ts 439 [2012-03-07 20:07:06.539] DEBUG[29376] jitterbuf.c: head 419, tail 439, ts 459 [2012-03-07 20:07:06.539] DEBUG[29376] jitterbuf.c: head 419, tail 459, ts 479 [2012-03-07 20:07:06.539] DEBUG[29376] jitterbuf.c: head 419, tail 479, ts 499 [2012-03-07 20:07:06.588] DEBUG[29380] jitterbuf.c: head 479, tail 499, ts 519 [2012-03-07 20:07:06.588] DEBUG[29380] jitterbuf.c: head 479, tail 519, ts 539 [2012-03-07 20:07:06.639] DEBUG[29383] jitterbuf.c: head 519, tail 539, ts 559 [2012-03-07 20:07:06.639] DEBUG[29383] jitterbuf.c: head 519, tail 559, ts 579 [2012-03-07 20:07:06.639] DEBUG[29383] jitterbuf.c: head 519, tail 579, ts 599 [2012-03-07 20:07:06.688] DEBUG[29377] jitterbuf.c: head 579, tail 599, ts 619 [2012-03-07 20:07:06.688] DEBUG[29377] jitterbuf.c: head 579, tail 619, ts 639 [2012-03-07 20:07:06.740] DEBUG[29380] jitterbuf.c: head 619, tail 639, ts 659 [2012-03-07 20:07:06.740] DEBUG[29380] jitterbuf.c: head 619, tail 659, ts 679 [2012-03-07 20:07:06.740] DEBUG[29380] jitterbuf.c: head 619, tail 679, ts 699 [2012-03-07 20:07:06.788] DEBUG[29374] jitterbuf.c: head 679, tail 699, ts 719 [2012-03-07 20:07:06.788] DEBUG[29374] jitterbuf.c: head 679, tail 719, ts 739 [2012-03-07 20:07:06.840] DEBUG[29377] jitterbuf.c: head 719, tail 739, ts 759 [2012-03-07 20:07:06.840] DEBUG[29377] jitterbuf.c: head 719, tail 759, ts 779 [2012-03-07 20:07:06.840] DEBUG[29377] jitterbuf.c: head 719, tail 779, ts 799 [2012-03-07 20:07:06.887] DEBUG[29381] jitterbuf.c: head 779, tail 799, ts 819 [2012-03-07 20:07:06.887] DEBUG[29381] jitterbuf.c: head 779, tail 819, ts 839 [2012-03-07 20:07:06.914] DEBUG[29386] chan_dahdi.c: Echo cancellation already on [2012-03-07 20:07:06.914] DEBUG[29403] chan_iax2.c: Indicating condition 22 [2012-03-07 20:07:06.914] VERBOSE[29403] app_dial.c: -- DAHDI/i2/5xxxx7-1 answered IAX2/trunk-1707 [2012-03-07 20:07:06.915] DEBUG[29403] chan_iax2.c: Answering IAX2 call [2012-03-07 20:07:06.915] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 003 Type: CONTROL Subclass: ANSWER [2012-03-07 20:07:06.915] VERBOSE[29372] chan_iax2.c: Timestamp: 00863ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.915] DEBUG[29403] chan_iax2.c: Indicating condition -1 [2012-03-07 20:07:06.915] DEBUG[29403] features.c: Removing dialed interfaces datastore on DAHDI/i2/5xxxx7-1 since we're bridging [2012-03-07 20:07:06.915] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 003 Type: CONTROL Subclass: (255?) [2012-03-07 20:07:06.915] DEBUG[29403] chan_iax2.c: Indicating condition 20 [2012-03-07 20:07:06.915] VERBOSE[29372] chan_iax2.c: Timestamp: 00866ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.915] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 003 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:06.915] VERBOSE[29372] chan_iax2.c: Timestamp: 00869ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.915] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:06.936] VERBOSE[29374] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 007 Type: IAX Subclass: ACK [2012-03-07 20:07:06.936] VERBOSE[29374] chan_iax2.c: Timestamp: 00863ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.936] DEBUG[29374] chan_iax2.c: Received packet 3, (6, 4) [2012-03-07 20:07:06.936] DEBUG[29374] chan_iax2.c: Cancelling transmission of packet 6 [2012-03-07 20:07:06.936] DEBUG[29374] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.941] DEBUG[29375] jitterbuf.c: head 819, tail 839, ts 859 [2012-03-07 20:07:06.941] DEBUG[29375] jitterbuf.c: head 819, tail 859, ts 879 [2012-03-07 20:07:06.941] DEBUG[29375] jitterbuf.c: head 819, tail 879, ts 899 [2012-03-07 20:07:06.942] VERBOSE[29376] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 008 Type: IAX Subclass: ACK [2012-03-07 20:07:06.942] VERBOSE[29376] chan_iax2.c: Timestamp: 00866ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.942] DEBUG[29376] chan_iax2.c: Received packet 3, (6, 4) [2012-03-07 20:07:06.942] DEBUG[29376] chan_iax2.c: Cancelling transmission of packet 7 [2012-03-07 20:07:06.942] DEBUG[29376] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.943] VERBOSE[29378] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 009 Type: IAX Subclass: ACK [2012-03-07 20:07:06.943] VERBOSE[29378] chan_iax2.c: Timestamp: 00869ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:06.943] DEBUG[29378] chan_iax2.c: Received packet 3, (6, 4) [2012-03-07 20:07:06.943] DEBUG[29378] chan_iax2.c: Cancelling transmission of packet 8 [2012-03-07 20:07:06.943] DEBUG[29378] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:06.986] DEBUG[29381] jitterbuf.c: head 879, tail 899, ts 919 [2012-03-07 20:07:06.986] DEBUG[29381] jitterbuf.c: head 879, tail 919, ts 939 [2012-03-07 20:07:07.037] DEBUG[29374] jitterbuf.c: head 919, tail 939, ts 959 [2012-03-07 20:07:07.037] DEBUG[29374] jitterbuf.c: head 919, tail 959, ts 979 [2012-03-07 20:07:07.037] DEBUG[29374] jitterbuf.c: head 919, tail 979, ts 999 [2012-03-07 20:07:07.045] VERBOSE[29376] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:07.045] VERBOSE[29376] chan_iax2.c: Timestamp: 01002ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:07.045] DEBUG[29376] chan_iax2.c: Received packet 3, (4, 20) [2012-03-07 20:07:07.045] VERBOSE[29376] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 004 Type: IAX Subclass: ACK [2012-03-07 20:07:07.045] VERBOSE[29376] chan_iax2.c: Timestamp: 01002ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:07.045] DEBUG[29376] jitterbuf.c: head 939, tail 999, ts 1002 [2012-03-07 20:07:07.045] DEBUG[29376] jitterbuf.c: Adding non VOICE frame to jb with ts 1002) [2012-03-07 20:07:07.045] DEBUG[29376] chan_iax2.c: For call=1707, set last=1002 [2012-03-07 20:07:07.047] VERBOSE[29377] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:07.047] VERBOSE[29377] chan_iax2.c: Timestamp: 01005ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:07.047] DEBUG[29377] chan_iax2.c: Received packet 4, (4, 20) [2012-03-07 20:07:07.047] VERBOSE[29377] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 005 Type: IAX Subclass: ACK [2012-03-07 20:07:07.047] VERBOSE[29377] chan_iax2.c: Timestamp: 01005ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:07.047] DEBUG[29377] jitterbuf.c: head 939, tail 1002, ts 1005 [2012-03-07 20:07:07.047] DEBUG[29377] jitterbuf.c: Adding non VOICE frame to jb with ts 1005) [2012-03-07 20:07:07.047] DEBUG[29377] chan_iax2.c: For call=1707, set last=1005 [2012-03-07 20:07:07.048] VERBOSE[29378] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:07.048] VERBOSE[29378] chan_iax2.c: Timestamp: 01008ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:07.048] DEBUG[29378] chan_iax2.c: Received packet 5, (4, 20) [2012-03-07 20:07:07.048] VERBOSE[29378] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 006 Type: IAX Subclass: ACK [2012-03-07 20:07:07.048] VERBOSE[29378] chan_iax2.c: Timestamp: 01008ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:07.048] DEBUG[29378] jitterbuf.c: head 939, tail 1005, ts 1008 [2012-03-07 20:07:07.048] DEBUG[29378] jitterbuf.c: Adding non VOICE frame to jb with ts 1008) [2012-03-07 20:07:07.048] DEBUG[29378] chan_iax2.c: For call=1707, set last=1008 [2012-03-07 20:07:07.086] DEBUG[29381] jitterbuf.c: head 979, tail 1008, ts 1019 [2012-03-07 20:07:07.086] DEBUG[29381] jitterbuf.c: head 979, tail 1019, ts 1039 [2012-03-07 20:07:07.136] DEBUG[29374] jitterbuf.c: head 1002, tail 1039, ts 1079 [2012-03-07 20:07:07.142] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:07.142] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:07.143] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:07.187] DEBUG[29381] jitterbuf.c: head 1079, tail 1079, ts 1099 [2012-03-07 20:07:07.187] DEBUG[29381] jitterbuf.c: head 1079, tail 1099, ts 1119 [2012-03-07 20:07:07.187] DEBUG[29381] jitterbuf.c: head 1079, tail 1119, ts 1139 [2012-03-07 20:07:07.238] DEBUG[29374] jitterbuf.c: head 1119, tail 1139, ts 1159 [2012-03-07 20:07:07.238] DEBUG[29374] jitterbuf.c: head 1119, tail 1159, ts 1179 [2012-03-07 20:07:07.289] DEBUG[29378] jitterbuf.c: head 1179, tail 1179, ts 1199 [2012-03-07 20:07:07.289] DEBUG[29378] jitterbuf.c: head 1179, tail 1199, ts 1219 [2012-03-07 20:07:07.289] DEBUG[29378] jitterbuf.c: head 1179, tail 1219, ts 1239 [2012-03-07 20:07:07.338] DEBUG[29381] jitterbuf.c: head 1199, tail 1239, ts 1259 [2012-03-07 20:07:07.338] DEBUG[29381] jitterbuf.c: head 1199, tail 1259, ts 1279 [2012-03-07 20:07:07.391] DEBUG[29375] jitterbuf.c: head 1259, tail 1279, ts 1299 [2012-03-07 20:07:07.391] DEBUG[29375] jitterbuf.c: head 1259, tail 1299, ts 1319 [2012-03-07 20:07:07.391] DEBUG[29375] jitterbuf.c: head 1259, tail 1319, ts 1339 [2012-03-07 20:07:07.440] DEBUG[29378] jitterbuf.c: head 1299, tail 1339, ts 1359 [2012-03-07 20:07:07.440] DEBUG[29378] jitterbuf.c: head 1299, tail 1359, ts 1379 [2012-03-07 20:07:07.489] DEBUG[29382] jitterbuf.c: head 1359, tail 1379, ts 1399 [2012-03-07 20:07:07.489] DEBUG[29382] jitterbuf.c: head 1359, tail 1399, ts 1419 [2012-03-07 20:07:07.489] DEBUG[29382] jitterbuf.c: head 1359, tail 1419, ts 1439 [2012-03-07 20:07:07.538] DEBUG[29375] jitterbuf.c: head 1399, tail 1439, ts 1459 [2012-03-07 20:07:07.538] DEBUG[29375] jitterbuf.c: head 1399, tail 1459, ts 1479 [2012-03-07 20:07:07.588] DEBUG[29379] jitterbuf.c: head 1459, tail 1479, ts 1499 [2012-03-07 20:07:07.588] DEBUG[29379] jitterbuf.c: head 1459, tail 1499, ts 1519 [2012-03-07 20:07:07.588] DEBUG[29379] jitterbuf.c: head 1459, tail 1519, ts 1539 [2012-03-07 20:07:07.636] DEBUG[29382] jitterbuf.c: head 1499, tail 1539, ts 1559 [2012-03-07 20:07:07.687] DEBUG[29376] jitterbuf.c: head 1559, tail 1559, ts 1579 [2012-03-07 20:07:07.687] DEBUG[29376] jitterbuf.c: head 1559, tail 1579, ts 1599 [2012-03-07 20:07:07.688] DEBUG[29376] jitterbuf.c: head 1559, tail 1599, ts 1619 [2012-03-07 20:07:07.688] DEBUG[29376] jitterbuf.c: head 1559, tail 1619, ts 1639 [2012-03-07 20:07:07.737] DEBUG[29379] jitterbuf.c: head 1599, tail 1639, ts 1659 [2012-03-07 20:07:07.737] DEBUG[29379] jitterbuf.c: head 1599, tail 1659, ts 1679 [2012-03-07 20:07:07.787] DEBUG[29383] jitterbuf.c: head 1659, tail 1679, ts 1699 [2012-03-07 20:07:07.787] DEBUG[29383] jitterbuf.c: head 1659, tail 1699, ts 1719 [2012-03-07 20:07:07.787] DEBUG[29383] jitterbuf.c: head 1659, tail 1719, ts 1739 [2012-03-07 20:07:07.836] DEBUG[29376] jitterbuf.c: head 1699, tail 1739, ts 1759 [2012-03-07 20:07:07.836] DEBUG[29376] jitterbuf.c: head 1699, tail 1759, ts 1779 [2012-03-07 20:07:07.887] DEBUG[29380] jitterbuf.c: head 1759, tail 1779, ts 1799 [2012-03-07 20:07:07.887] DEBUG[29380] jitterbuf.c: head 1759, tail 1799, ts 1819 [2012-03-07 20:07:07.887] DEBUG[29380] jitterbuf.c: head 1759, tail 1819, ts 1839 [2012-03-07 20:07:07.937] DEBUG[29383] jitterbuf.c: head 1799, tail 1839, ts 1859 [2012-03-07 20:07:07.937] DEBUG[29383] jitterbuf.c: head 1799, tail 1859, ts 1879 [2012-03-07 20:07:07.986] DEBUG[29377] jitterbuf.c: head 1859, tail 1879, ts 1899 [2012-03-07 20:07:07.986] DEBUG[29377] jitterbuf.c: head 1859, tail 1899, ts 1919 [2012-03-07 20:07:07.986] DEBUG[29377] jitterbuf.c: head 1859, tail 1919, ts 1939 [2012-03-07 20:07:08.037] DEBUG[29380] jitterbuf.c: head 1899, tail 1939, ts 1959 [2012-03-07 20:07:08.037] DEBUG[29380] jitterbuf.c: head 1899, tail 1959, ts 1979 [2012-03-07 20:07:08.087] DEBUG[29376] jitterbuf.c: head 1959, tail 1979, ts 1999 [2012-03-07 20:07:08.087] DEBUG[29376] jitterbuf.c: head 1959, tail 1999, ts 2019 [2012-03-07 20:07:08.087] DEBUG[29376] jitterbuf.c: head 1959, tail 2019, ts 2039 [2012-03-07 20:07:08.136] DEBUG[29379] jitterbuf.c: head 1999, tail 2039, ts 2059 [2012-03-07 20:07:08.136] DEBUG[29379] jitterbuf.c: head 1999, tail 2059, ts 2079 [2012-03-07 20:07:08.187] DEBUG[29375] jitterbuf.c: head 2059, tail 2079, ts 2099 [2012-03-07 20:07:08.187] DEBUG[29375] jitterbuf.c: head 2059, tail 2099, ts 2119 [2012-03-07 20:07:08.187] DEBUG[29375] jitterbuf.c: head 2059, tail 2119, ts 2139 [2012-03-07 20:07:08.237] DEBUG[29378] jitterbuf.c: head 2099, tail 2139, ts 2159 [2012-03-07 20:07:08.237] DEBUG[29378] jitterbuf.c: head 2099, tail 2159, ts 2179 [2012-03-07 20:07:08.287] DEBUG[29382] jitterbuf.c: head 2159, tail 2179, ts 2199 [2012-03-07 20:07:08.287] DEBUG[29382] jitterbuf.c: head 2159, tail 2199, ts 2219 [2012-03-07 20:07:08.287] DEBUG[29382] jitterbuf.c: head 2159, tail 2219, ts 2239 [2012-03-07 20:07:08.336] DEBUG[29377] jitterbuf.c: head 2199, tail 2239, ts 2259 [2012-03-07 20:07:08.336] DEBUG[29377] jitterbuf.c: head 2199, tail 2259, ts 2279 [2012-03-07 20:07:08.387] DEBUG[29381] jitterbuf.c: head 2259, tail 2279, ts 2299 [2012-03-07 20:07:08.387] DEBUG[29381] jitterbuf.c: head 2259, tail 2299, ts 2319 [2012-03-07 20:07:08.387] DEBUG[29381] jitterbuf.c: head 2259, tail 2319, ts 2339 [2012-03-07 20:07:08.436] DEBUG[29374] jitterbuf.c: head 2299, tail 2339, ts 2359 [2012-03-07 20:07:08.436] DEBUG[29374] jitterbuf.c: head 2299, tail 2359, ts 2379 [2012-03-07 20:07:08.487] DEBUG[29378] jitterbuf.c: head 2359, tail 2379, ts 2399 [2012-03-07 20:07:08.487] DEBUG[29378] jitterbuf.c: head 2359, tail 2399, ts 2419 [2012-03-07 20:07:08.487] DEBUG[29378] jitterbuf.c: head 2359, tail 2419, ts 2439 [2012-03-07 20:07:08.537] DEBUG[29381] jitterbuf.c: head 2399, tail 2439, ts 2459 [2012-03-07 20:07:08.537] DEBUG[29381] jitterbuf.c: head 2399, tail 2459, ts 2479 [2012-03-07 20:07:08.587] DEBUG[29375] jitterbuf.c: head 2459, tail 2479, ts 2499 [2012-03-07 20:07:08.587] DEBUG[29375] jitterbuf.c: head 2459, tail 2499, ts 2519 [2012-03-07 20:07:08.587] DEBUG[29375] jitterbuf.c: head 2459, tail 2519, ts 2539 [2012-03-07 20:07:08.636] DEBUG[29378] jitterbuf.c: head 2499, tail 2539, ts 2559 [2012-03-07 20:07:08.636] DEBUG[29378] jitterbuf.c: head 2499, tail 2559, ts 2579 [2012-03-07 20:07:08.687] DEBUG[29382] jitterbuf.c: head 2559, tail 2579, ts 2599 [2012-03-07 20:07:08.687] DEBUG[29382] jitterbuf.c: head 2559, tail 2599, ts 2619 [2012-03-07 20:07:08.687] DEBUG[29382] jitterbuf.c: head 2559, tail 2619, ts 2639 [2012-03-07 20:07:08.736] DEBUG[29375] jitterbuf.c: head 2599, tail 2639, ts 2659 [2012-03-07 20:07:08.736] DEBUG[29375] jitterbuf.c: head 2599, tail 2659, ts 2679 [2012-03-07 20:07:08.787] DEBUG[29379] jitterbuf.c: head 2659, tail 2679, ts 2699 [2012-03-07 20:07:08.787] DEBUG[29379] jitterbuf.c: head 2659, tail 2699, ts 2719 [2012-03-07 20:07:08.787] DEBUG[29379] jitterbuf.c: head 2659, tail 2719, ts 2739 [2012-03-07 20:07:08.836] DEBUG[29382] jitterbuf.c: head 2699, tail 2739, ts 2759 [2012-03-07 20:07:08.836] DEBUG[29382] jitterbuf.c: head 2699, tail 2759, ts 2779 [2012-03-07 20:07:08.886] DEBUG[29376] jitterbuf.c: head 2759, tail 2779, ts 2799 [2012-03-07 20:07:08.886] DEBUG[29376] jitterbuf.c: head 2759, tail 2799, ts 2819 [2012-03-07 20:07:08.886] DEBUG[29376] jitterbuf.c: head 2759, tail 2819, ts 2839 [2012-03-07 20:07:08.936] DEBUG[29382] jitterbuf.c: head 2799, tail 2839, ts 2859 [2012-03-07 20:07:08.936] DEBUG[29382] jitterbuf.c: head 2799, tail 2859, ts 2879 [2012-03-07 20:07:08.987] DEBUG[29376] jitterbuf.c: head 2859, tail 2879, ts 2899 [2012-03-07 20:07:08.987] DEBUG[29376] jitterbuf.c: head 2859, tail 2899, ts 2919 [2012-03-07 20:07:08.987] DEBUG[29376] jitterbuf.c: head 2859, tail 2919, ts 2939 [2012-03-07 20:07:09.036] DEBUG[29379] jitterbuf.c: head 2899, tail 2939, ts 2959 [2012-03-07 20:07:09.036] DEBUG[29379] jitterbuf.c: head 2899, tail 2959, ts 2979 [2012-03-07 20:07:09.087] DEBUG[29383] jitterbuf.c: head 2959, tail 2979, ts 2999 [2012-03-07 20:07:09.087] DEBUG[29383] jitterbuf.c: head 2959, tail 2999, ts 3019 [2012-03-07 20:07:09.087] DEBUG[29383] jitterbuf.c: head 2959, tail 3019, ts 3039 [2012-03-07 20:07:09.137] DEBUG[29376] jitterbuf.c: head 2999, tail 3039, ts 3059 [2012-03-07 20:07:09.137] DEBUG[29376] jitterbuf.c: head 2999, tail 3059, ts 3079 [2012-03-07 20:07:09.187] DEBUG[29380] jitterbuf.c: head 3059, tail 3079, ts 3099 [2012-03-07 20:07:09.187] DEBUG[29380] jitterbuf.c: head 3059, tail 3099, ts 3119 [2012-03-07 20:07:09.187] DEBUG[29380] jitterbuf.c: head 3059, tail 3119, ts 3139 [2012-03-07 20:07:09.236] DEBUG[29383] jitterbuf.c: head 3099, tail 3139, ts 3159 [2012-03-07 20:07:09.236] DEBUG[29383] jitterbuf.c: head 3099, tail 3159, ts 3179 [2012-03-07 20:07:09.287] DEBUG[29377] jitterbuf.c: head 3159, tail 3179, ts 3199 [2012-03-07 20:07:09.287] DEBUG[29377] jitterbuf.c: head 3159, tail 3199, ts 3219 [2012-03-07 20:07:09.287] DEBUG[29377] jitterbuf.c: head 3159, tail 3219, ts 3239 [2012-03-07 20:07:09.336] DEBUG[29380] jitterbuf.c: head 3199, tail 3239, ts 3259 [2012-03-07 20:07:09.336] DEBUG[29380] jitterbuf.c: head 3199, tail 3259, ts 3279 [2012-03-07 20:07:09.387] DEBUG[29374] jitterbuf.c: head 3259, tail 3279, ts 3299 [2012-03-07 20:07:09.387] DEBUG[29374] jitterbuf.c: head 3259, tail 3299, ts 3319 [2012-03-07 20:07:09.387] DEBUG[29374] jitterbuf.c: head 3259, tail 3319, ts 3339 [2012-03-07 20:07:09.436] DEBUG[29377] jitterbuf.c: head 3299, tail 3339, ts 3359 [2012-03-07 20:07:09.436] DEBUG[29377] jitterbuf.c: head 3299, tail 3359, ts 3379 [2012-03-07 20:07:09.474] VERBOSE[29380] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:09.474] VERBOSE[29380] chan_iax2.c: Timestamp: 03402ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:09.474] DEBUG[29380] chan_iax2.c: Received packet 6, (4, 20) [2012-03-07 20:07:09.474] VERBOSE[29380] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 007 Type: IAX Subclass: ACK [2012-03-07 20:07:09.474] VERBOSE[29380] chan_iax2.c: Timestamp: 03402ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:09.474] DEBUG[29380] jitterbuf.c: head 3339, tail 3379, ts 3402 [2012-03-07 20:07:09.474] DEBUG[29380] jitterbuf.c: Adding non VOICE frame to jb with ts 3402) [2012-03-07 20:07:09.474] DEBUG[29380] chan_iax2.c: For call=1707, set last=3402 [2012-03-07 20:07:09.476] VERBOSE[29381] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:09.476] VERBOSE[29381] chan_iax2.c: Timestamp: 03405ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:09.476] DEBUG[29381] chan_iax2.c: Received packet 7, (4, 20) [2012-03-07 20:07:09.476] VERBOSE[29381] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 008 Type: IAX Subclass: ACK [2012-03-07 20:07:09.476] VERBOSE[29381] chan_iax2.c: Timestamp: 03405ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:09.476] DEBUG[29381] jitterbuf.c: head 3339, tail 3402, ts 3405 [2012-03-07 20:07:09.476] DEBUG[29381] jitterbuf.c: Adding non VOICE frame to jb with ts 3405) [2012-03-07 20:07:09.476] DEBUG[29381] chan_iax2.c: For call=1707, set last=3405 [2012-03-07 20:07:09.477] VERBOSE[29382] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:09.477] VERBOSE[29382] chan_iax2.c: Timestamp: 03408ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:09.477] DEBUG[29382] chan_iax2.c: Received packet 8, (4, 20) [2012-03-07 20:07:09.477] VERBOSE[29382] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 009 Type: IAX Subclass: ACK [2012-03-07 20:07:09.477] VERBOSE[29382] chan_iax2.c: Timestamp: 03408ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:09.477] DEBUG[29382] jitterbuf.c: head 3339, tail 3405, ts 3408 [2012-03-07 20:07:09.477] DEBUG[29382] jitterbuf.c: Adding non VOICE frame to jb with ts 3408) [2012-03-07 20:07:09.477] DEBUG[29382] chan_iax2.c: For call=1707, set last=3408 [2012-03-07 20:07:09.486] DEBUG[29374] jitterbuf.c: head 3359, tail 3408, ts 3399 [2012-03-07 20:07:09.486] DEBUG[29374] jitterbuf.c: head 3359, tail 3408, ts 3480 [2012-03-07 20:07:09.536] DEBUG[29377] jitterbuf.c: head 3399, tail 3480, ts 3500 [2012-03-07 20:07:09.536] DEBUG[29377] jitterbuf.c: head 3399, tail 3500, ts 3520 [2012-03-07 20:07:09.563] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:09.563] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:09.563] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:09.591] DEBUG[29374] jitterbuf.c: head 3480, tail 3520, ts 3540 [2012-03-07 20:07:09.591] DEBUG[29374] jitterbuf.c: head 3480, tail 3540, ts 3560 [2012-03-07 20:07:09.591] DEBUG[29374] jitterbuf.c: head 3480, tail 3560, ts 3580 [2012-03-07 20:07:09.640] DEBUG[29377] jitterbuf.c: head 3480, tail 3580, ts 3600 [2012-03-07 20:07:09.640] DEBUG[29377] jitterbuf.c: head 3480, tail 3600, ts 3620 [2012-03-07 20:07:09.690] DEBUG[29382] jitterbuf.c: head 3560, tail 3620, ts 3640 [2012-03-07 20:07:09.690] DEBUG[29382] jitterbuf.c: head 3560, tail 3640, ts 3660 [2012-03-07 20:07:09.690] DEBUG[29382] jitterbuf.c: head 3560, tail 3660, ts 3680 [2012-03-07 20:07:09.739] DEBUG[29375] jitterbuf.c: head 3600, tail 3680, ts 3700 [2012-03-07 20:07:09.739] DEBUG[29375] jitterbuf.c: head 3600, tail 3700, ts 3720 [2012-03-07 20:07:09.790] DEBUG[29379] jitterbuf.c: head 3660, tail 3720, ts 3740 [2012-03-07 20:07:09.790] DEBUG[29379] jitterbuf.c: head 3660, tail 3740, ts 3760 [2012-03-07 20:07:09.790] DEBUG[29379] jitterbuf.c: head 3660, tail 3760, ts 3780 [2012-03-07 20:07:09.838] DEBUG[29382] jitterbuf.c: head 3700, tail 3780, ts 3800 [2012-03-07 20:07:09.838] DEBUG[29382] jitterbuf.c: head 3700, tail 3800, ts 3820 [2012-03-07 20:07:09.890] DEBUG[29376] jitterbuf.c: head 3760, tail 3820, ts 3840 [2012-03-07 20:07:09.890] DEBUG[29376] jitterbuf.c: head 3760, tail 3840, ts 3860 [2012-03-07 20:07:09.890] DEBUG[29376] jitterbuf.c: head 3760, tail 3860, ts 3880 [2012-03-07 20:07:09.938] DEBUG[29379] jitterbuf.c: head 3800, tail 3880, ts 3900 [2012-03-07 20:07:09.938] DEBUG[29379] jitterbuf.c: head 3800, tail 3900, ts 3920 [2012-03-07 20:07:09.989] DEBUG[29383] jitterbuf.c: head 3860, tail 3920, ts 3940 [2012-03-07 20:07:09.989] DEBUG[29383] jitterbuf.c: head 3860, tail 3940, ts 3960 [2012-03-07 20:07:09.989] DEBUG[29383] jitterbuf.c: head 3860, tail 3960, ts 3980 [2012-03-07 20:07:10.038] DEBUG[29376] jitterbuf.c: head 3900, tail 3980, ts 4000 [2012-03-07 20:07:10.039] DEBUG[29376] jitterbuf.c: head 3900, tail 4000, ts 4020 [2012-03-07 20:07:10.090] DEBUG[29380] jitterbuf.c: head 3960, tail 4020, ts 4040 [2012-03-07 20:07:10.090] DEBUG[29380] jitterbuf.c: head 3960, tail 4040, ts 4060 [2012-03-07 20:07:10.090] DEBUG[29380] jitterbuf.c: head 3960, tail 4060, ts 4080 [2012-03-07 20:07:10.138] DEBUG[29383] jitterbuf.c: head 4000, tail 4080, ts 4100 [2012-03-07 20:07:10.138] DEBUG[29383] jitterbuf.c: head 4000, tail 4100, ts 4120 [2012-03-07 20:07:10.189] DEBUG[29377] jitterbuf.c: head 4060, tail 4120, ts 4140 [2012-03-07 20:07:10.189] DEBUG[29377] jitterbuf.c: head 4060, tail 4140, ts 4160 [2012-03-07 20:07:10.189] DEBUG[29377] jitterbuf.c: head 4060, tail 4160, ts 4180 [2012-03-07 20:07:10.238] DEBUG[29380] jitterbuf.c: head 4100, tail 4180, ts 4200 [2012-03-07 20:07:10.238] DEBUG[29380] jitterbuf.c: head 4100, tail 4200, ts 4220 [2012-03-07 20:07:10.290] DEBUG[29374] jitterbuf.c: head 4160, tail 4220, ts 4240 [2012-03-07 20:07:10.290] DEBUG[29374] jitterbuf.c: head 4160, tail 4240, ts 4260 [2012-03-07 20:07:10.290] DEBUG[29374] jitterbuf.c: head 4160, tail 4260, ts 4280 [2012-03-07 20:07:10.339] DEBUG[29377] jitterbuf.c: head 4200, tail 4280, ts 4300 [2012-03-07 20:07:10.339] DEBUG[29377] jitterbuf.c: head 4200, tail 4300, ts 4320 [2012-03-07 20:07:10.390] DEBUG[29381] jitterbuf.c: head 4260, tail 4320, ts 4340 [2012-03-07 20:07:10.390] DEBUG[29381] jitterbuf.c: head 4260, tail 4340, ts 4360 [2012-03-07 20:07:10.390] DEBUG[29381] jitterbuf.c: head 4260, tail 4360, ts 4380 [2012-03-07 20:07:10.438] DEBUG[29374] jitterbuf.c: head 4300, tail 4380, ts 4400 [2012-03-07 20:07:10.439] DEBUG[29374] jitterbuf.c: head 4300, tail 4400, ts 4420 [2012-03-07 20:07:10.490] DEBUG[29378] jitterbuf.c: head 4360, tail 4420, ts 4440 [2012-03-07 20:07:10.490] DEBUG[29378] jitterbuf.c: head 4360, tail 4440, ts 4460 [2012-03-07 20:07:10.490] DEBUG[29378] jitterbuf.c: head 4360, tail 4460, ts 4480 [2012-03-07 20:07:10.539] DEBUG[29381] jitterbuf.c: head 4400, tail 4480, ts 4500 [2012-03-07 20:07:10.539] DEBUG[29381] jitterbuf.c: head 4400, tail 4500, ts 4520 [2012-03-07 20:07:10.591] DEBUG[29375] jitterbuf.c: head 4460, tail 4520, ts 4540 [2012-03-07 20:07:10.591] DEBUG[29375] jitterbuf.c: head 4460, tail 4540, ts 4560 [2012-03-07 20:07:10.591] DEBUG[29375] jitterbuf.c: head 4460, tail 4560, ts 4580 [2012-03-07 20:07:10.639] DEBUG[29378] jitterbuf.c: head 4500, tail 4580, ts 4600 [2012-03-07 20:07:10.639] DEBUG[29378] jitterbuf.c: head 4500, tail 4600, ts 4620 [2012-03-07 20:07:10.687] DEBUG[29382] jitterbuf.c: head 4560, tail 4620, ts 4640 [2012-03-07 20:07:10.687] DEBUG[29382] jitterbuf.c: head 4560, tail 4640, ts 4660 [2012-03-07 20:07:10.687] DEBUG[29382] jitterbuf.c: head 4560, tail 4660, ts 4680 [2012-03-07 20:07:10.737] DEBUG[29375] jitterbuf.c: head 4600, tail 4680, ts 4700 [2012-03-07 20:07:10.737] DEBUG[29375] jitterbuf.c: head 4600, tail 4700, ts 4720 [2012-03-07 20:07:10.791] DEBUG[29379] jitterbuf.c: head 4660, tail 4720, ts 4740 [2012-03-07 20:07:10.791] DEBUG[29379] jitterbuf.c: head 4660, tail 4740, ts 4760 [2012-03-07 20:07:10.791] DEBUG[29379] jitterbuf.c: head 4660, tail 4760, ts 4780 [2012-03-07 20:07:10.838] DEBUG[29382] jitterbuf.c: head 4700, tail 4780, ts 4800 [2012-03-07 20:07:10.839] DEBUG[29382] jitterbuf.c: head 4700, tail 4800, ts 4820 [2012-03-07 20:07:10.890] DEBUG[29376] jitterbuf.c: head 4760, tail 4820, ts 4840 [2012-03-07 20:07:10.890] DEBUG[29376] jitterbuf.c: head 4760, tail 4840, ts 4860 [2012-03-07 20:07:10.890] DEBUG[29376] jitterbuf.c: head 4760, tail 4860, ts 4880 [2012-03-07 20:07:10.939] DEBUG[29379] jitterbuf.c: head 4800, tail 4880, ts 4900 [2012-03-07 20:07:10.939] DEBUG[29379] jitterbuf.c: head 4800, tail 4900, ts 4920 [2012-03-07 20:07:10.991] DEBUG[29383] jitterbuf.c: head 4860, tail 4920, ts 4940 [2012-03-07 20:07:10.991] DEBUG[29383] jitterbuf.c: head 4860, tail 4940, ts 4960 [2012-03-07 20:07:10.991] DEBUG[29383] jitterbuf.c: head 4860, tail 4960, ts 4980 [2012-03-07 20:07:11.039] DEBUG[29376] jitterbuf.c: head 4900, tail 4980, ts 5000 [2012-03-07 20:07:11.039] DEBUG[29376] jitterbuf.c: head 4900, tail 5000, ts 5020 [2012-03-07 20:07:11.090] DEBUG[29380] jitterbuf.c: head 4960, tail 5020, ts 5040 [2012-03-07 20:07:11.090] DEBUG[29380] jitterbuf.c: head 4960, tail 5040, ts 5060 [2012-03-07 20:07:11.090] DEBUG[29380] jitterbuf.c: head 4960, tail 5060, ts 5080 [2012-03-07 20:07:11.139] DEBUG[29383] jitterbuf.c: head 5000, tail 5080, ts 5100 [2012-03-07 20:07:11.139] DEBUG[29383] jitterbuf.c: head 5000, tail 5100, ts 5120 [2012-03-07 20:07:11.190] DEBUG[29377] jitterbuf.c: head 5060, tail 5120, ts 5140 [2012-03-07 20:07:11.190] DEBUG[29377] jitterbuf.c: head 5060, tail 5140, ts 5160 [2012-03-07 20:07:11.190] DEBUG[29377] jitterbuf.c: head 5060, tail 5160, ts 5180 [2012-03-07 20:07:11.237] DEBUG[29380] jitterbuf.c: head 5100, tail 5180, ts 5200 [2012-03-07 20:07:11.237] DEBUG[29380] jitterbuf.c: head 5100, tail 5200, ts 5220 [2012-03-07 20:07:11.290] DEBUG[29374] jitterbuf.c: head 5160, tail 5220, ts 5240 [2012-03-07 20:07:11.290] DEBUG[29374] jitterbuf.c: head 5160, tail 5240, ts 5260 [2012-03-07 20:07:11.290] DEBUG[29374] jitterbuf.c: head 5160, tail 5260, ts 5280 [2012-03-07 20:07:11.339] DEBUG[29377] jitterbuf.c: head 5200, tail 5280, ts 5300 [2012-03-07 20:07:11.339] DEBUG[29377] jitterbuf.c: head 5200, tail 5300, ts 5320 [2012-03-07 20:07:11.391] DEBUG[29381] jitterbuf.c: head 5260, tail 5320, ts 5340 [2012-03-07 20:07:11.391] DEBUG[29381] jitterbuf.c: head 5260, tail 5340, ts 5360 [2012-03-07 20:07:11.391] DEBUG[29381] jitterbuf.c: head 5260, tail 5360, ts 5380 [2012-03-07 20:07:11.439] DEBUG[29374] jitterbuf.c: head 5300, tail 5380, ts 5400 [2012-03-07 20:07:11.439] DEBUG[29374] jitterbuf.c: head 5300, tail 5400, ts 5420 [2012-03-07 20:07:11.491] DEBUG[29378] jitterbuf.c: head 5360, tail 5420, ts 5440 [2012-03-07 20:07:11.491] DEBUG[29378] jitterbuf.c: head 5360, tail 5440, ts 5460 [2012-03-07 20:07:11.491] DEBUG[29378] jitterbuf.c: head 5360, tail 5460, ts 5480 [2012-03-07 20:07:11.549] DEBUG[29382] jitterbuf.c: head 5420, tail 5480, ts 5500 [2012-03-07 20:07:11.549] DEBUG[29382] jitterbuf.c: head 5420, tail 5500, ts 5520 [2012-03-07 20:07:11.591] DEBUG[29375] jitterbuf.c: head 5460, tail 5520, ts 5540 [2012-03-07 20:07:11.591] DEBUG[29375] jitterbuf.c: head 5460, tail 5540, ts 5560 [2012-03-07 20:07:11.591] DEBUG[29375] jitterbuf.c: head 5460, tail 5560, ts 5580 [2012-03-07 20:07:11.639] DEBUG[29378] jitterbuf.c: head 5500, tail 5580, ts 5600 [2012-03-07 20:07:11.639] DEBUG[29378] jitterbuf.c: head 5500, tail 5600, ts 5620 [2012-03-07 20:07:11.691] DEBUG[29382] jitterbuf.c: head 5560, tail 5620, ts 5640 [2012-03-07 20:07:11.691] DEBUG[29382] jitterbuf.c: head 5560, tail 5640, ts 5660 [2012-03-07 20:07:11.691] DEBUG[29382] jitterbuf.c: head 5560, tail 5660, ts 5680 [2012-03-07 20:07:11.739] DEBUG[29375] jitterbuf.c: head 5600, tail 5680, ts 5700 [2012-03-07 20:07:11.739] DEBUG[29375] jitterbuf.c: head 5600, tail 5700, ts 5720 [2012-03-07 20:07:11.790] DEBUG[29379] jitterbuf.c: head 5660, tail 5720, ts 5740 [2012-03-07 20:07:11.790] DEBUG[29379] jitterbuf.c: head 5660, tail 5740, ts 5760 [2012-03-07 20:07:11.791] DEBUG[29379] jitterbuf.c: head 5660, tail 5760, ts 5780 [2012-03-07 20:07:11.839] DEBUG[29382] jitterbuf.c: head 5700, tail 5780, ts 5800 [2012-03-07 20:07:11.839] DEBUG[29382] jitterbuf.c: head 5700, tail 5800, ts 5820 [2012-03-07 20:07:11.890] DEBUG[29376] jitterbuf.c: head 5760, tail 5820, ts 5840 [2012-03-07 20:07:11.890] DEBUG[29376] jitterbuf.c: head 5760, tail 5840, ts 5860 [2012-03-07 20:07:11.890] DEBUG[29376] jitterbuf.c: head 5760, tail 5860, ts 5880 [2012-03-07 20:07:11.940] DEBUG[29379] jitterbuf.c: head 5800, tail 5880, ts 5900 [2012-03-07 20:07:11.940] DEBUG[29379] jitterbuf.c: head 5800, tail 5900, ts 5920 [2012-03-07 20:07:11.991] DEBUG[29383] jitterbuf.c: head 5860, tail 5920, ts 5940 [2012-03-07 20:07:11.991] DEBUG[29383] jitterbuf.c: head 5860, tail 5940, ts 5960 [2012-03-07 20:07:11.991] DEBUG[29383] jitterbuf.c: head 5860, tail 5960, ts 5980 [2012-03-07 20:07:12.039] DEBUG[29376] jitterbuf.c: head 5900, tail 5980, ts 6000 [2012-03-07 20:07:12.039] DEBUG[29376] jitterbuf.c: head 5900, tail 6000, ts 6020 [2012-03-07 20:07:12.090] DEBUG[29380] jitterbuf.c: head 5960, tail 6020, ts 6040 [2012-03-07 20:07:12.090] DEBUG[29380] jitterbuf.c: head 5960, tail 6040, ts 6060 [2012-03-07 20:07:12.090] DEBUG[29380] jitterbuf.c: head 5960, tail 6060, ts 6080 [2012-03-07 20:07:12.140] DEBUG[29383] jitterbuf.c: head 6000, tail 6080, ts 6100 [2012-03-07 20:07:12.140] DEBUG[29383] jitterbuf.c: head 6000, tail 6100, ts 6120 [2012-03-07 20:07:12.190] DEBUG[29377] jitterbuf.c: head 6060, tail 6120, ts 6140 [2012-03-07 20:07:12.190] DEBUG[29377] jitterbuf.c: head 6060, tail 6140, ts 6160 [2012-03-07 20:07:12.190] DEBUG[29377] jitterbuf.c: head 6060, tail 6160, ts 6180 [2012-03-07 20:07:12.239] DEBUG[29380] jitterbuf.c: head 6100, tail 6180, ts 6200 [2012-03-07 20:07:12.239] DEBUG[29380] jitterbuf.c: head 6100, tail 6200, ts 6220 [2012-03-07 20:07:12.290] DEBUG[29374] jitterbuf.c: head 6160, tail 6220, ts 6240 [2012-03-07 20:07:12.290] DEBUG[29374] jitterbuf.c: head 6160, tail 6240, ts 6260 [2012-03-07 20:07:12.290] DEBUG[29374] jitterbuf.c: head 6160, tail 6260, ts 6280 [2012-03-07 20:07:12.339] DEBUG[29377] jitterbuf.c: head 6200, tail 6280, ts 6300 [2012-03-07 20:07:12.339] DEBUG[29377] jitterbuf.c: head 6200, tail 6300, ts 6320 [2012-03-07 20:07:12.389] DEBUG[29381] jitterbuf.c: head 6260, tail 6320, ts 6340 [2012-03-07 20:07:12.389] DEBUG[29381] jitterbuf.c: head 6260, tail 6340, ts 6360 [2012-03-07 20:07:12.389] DEBUG[29381] jitterbuf.c: head 6260, tail 6360, ts 6380 [2012-03-07 20:07:12.439] DEBUG[29374] jitterbuf.c: head 6300, tail 6380, ts 6400 [2012-03-07 20:07:12.439] DEBUG[29374] jitterbuf.c: head 6300, tail 6400, ts 6420 [2012-03-07 20:07:12.490] DEBUG[29378] jitterbuf.c: head 6360, tail 6420, ts 6440 [2012-03-07 20:07:12.490] DEBUG[29378] jitterbuf.c: head 6360, tail 6440, ts 6460 [2012-03-07 20:07:12.490] DEBUG[29378] jitterbuf.c: head 6360, tail 6460, ts 6480 [2012-03-07 20:07:12.538] DEBUG[29381] jitterbuf.c: head 6400, tail 6480, ts 6500 [2012-03-07 20:07:12.538] DEBUG[29381] jitterbuf.c: head 6400, tail 6500, ts 6520 [2012-03-07 20:07:12.589] DEBUG[29375] jitterbuf.c: head 6460, tail 6520, ts 6540 [2012-03-07 20:07:12.589] DEBUG[29375] jitterbuf.c: head 6460, tail 6540, ts 6560 [2012-03-07 20:07:12.589] DEBUG[29375] jitterbuf.c: head 6460, tail 6560, ts 6580 [2012-03-07 20:07:12.638] DEBUG[29378] jitterbuf.c: head 6500, tail 6580, ts 6600 [2012-03-07 20:07:12.638] DEBUG[29378] jitterbuf.c: head 6500, tail 6600, ts 6620 [2012-03-07 20:07:12.694] VERBOSE[29382] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:12.694] VERBOSE[29382] chan_iax2.c: Timestamp: 06663ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:12.694] DEBUG[29382] chan_iax2.c: Received packet 9, (4, 20) [2012-03-07 20:07:12.694] VERBOSE[29382] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 010 Type: IAX Subclass: ACK [2012-03-07 20:07:12.694] VERBOSE[29382] chan_iax2.c: Timestamp: 06663ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:12.694] DEBUG[29382] jitterbuf.c: head 6560, tail 6620, ts 6663 [2012-03-07 20:07:12.694] DEBUG[29382] jitterbuf.c: Adding non VOICE frame to jb with ts 6663) [2012-03-07 20:07:12.694] DEBUG[29382] chan_iax2.c: For call=1707, set last=6663 [2012-03-07 20:07:12.696] VERBOSE[29383] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:12.696] VERBOSE[29383] chan_iax2.c: Timestamp: 06666ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:12.696] DEBUG[29383] chan_iax2.c: Received packet 10, (4, 20) [2012-03-07 20:07:12.696] VERBOSE[29383] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 011 Type: IAX Subclass: ACK [2012-03-07 20:07:12.696] VERBOSE[29383] chan_iax2.c: Timestamp: 06666ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:12.696] DEBUG[29383] jitterbuf.c: head 6560, tail 6663, ts 6666 [2012-03-07 20:07:12.696] DEBUG[29383] jitterbuf.c: Adding non VOICE frame to jb with ts 6666) [2012-03-07 20:07:12.696] DEBUG[29383] chan_iax2.c: For call=1707, set last=6666 [2012-03-07 20:07:12.697] VERBOSE[29374] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:12.697] VERBOSE[29374] chan_iax2.c: Timestamp: 06669ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:12.697] DEBUG[29374] chan_iax2.c: Received packet 11, (4, 20) [2012-03-07 20:07:12.697] VERBOSE[29374] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 012 Type: IAX Subclass: ACK [2012-03-07 20:07:12.698] VERBOSE[29374] chan_iax2.c: Timestamp: 06669ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:12.698] DEBUG[29374] jitterbuf.c: head 6560, tail 6666, ts 6669 [2012-03-07 20:07:12.698] DEBUG[29374] jitterbuf.c: Adding non VOICE frame to jb with ts 6669) [2012-03-07 20:07:12.698] DEBUG[29374] chan_iax2.c: For call=1707, set last=6669 [2012-03-07 20:07:12.702] DEBUG[29375] jitterbuf.c: head 6560, tail 6669, ts 6640 [2012-03-07 20:07:12.702] DEBUG[29375] jitterbuf.c: head 6560, tail 6669, ts 6660 [2012-03-07 20:07:12.824] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:12.824] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:12.824] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:13.037] WARNING[29376] chan_iax2.c: Resyncing the jb. last_delay 17, this delay 3524, threshold 1154, new offset -3524 [2012-03-07 20:07:13.037] DEBUG[29376] jitterbuf.c: head 7012, tail 7012, ts 3508 [2012-03-07 20:07:13.087] DEBUG[29377] jitterbuf.c: head 7012, tail 7032, ts 3528 [2012-03-07 20:07:13.087] DEBUG[29377] jitterbuf.c: head 7012, tail 7052, ts 3548 [2012-03-07 20:07:13.087] DEBUG[29377] jitterbuf.c: head 7012, tail 7072, ts 3568 [2012-03-07 20:07:13.136] DEBUG[29378] jitterbuf.c: head 7012, tail 7092, ts 3588 [2012-03-07 20:07:13.136] DEBUG[29378] jitterbuf.c: head 7012, tail 7112, ts 3608 [2012-03-07 20:07:13.186] DEBUG[29375] jitterbuf.c: head 7132, tail 7132, ts 3628 [2012-03-07 20:07:13.187] DEBUG[29375] jitterbuf.c: head 7132, tail 7152, ts 3648 [2012-03-07 20:07:13.187] DEBUG[29375] jitterbuf.c: head 7132, tail 7172, ts 3668 [2012-03-07 20:07:13.236] DEBUG[29378] jitterbuf.c: head 7172, tail 7192, ts 3688 [2012-03-07 20:07:13.236] DEBUG[29378] jitterbuf.c: head 7172, tail 7212, ts 3708 [2012-03-07 20:07:13.287] DEBUG[29382] jitterbuf.c: head 7232, tail 7232, ts 3728 [2012-03-07 20:07:13.287] DEBUG[29382] jitterbuf.c: head 7232, tail 7252, ts 3748 [2012-03-07 20:07:13.287] DEBUG[29382] jitterbuf.c: head 7232, tail 7272, ts 3768 [2012-03-07 20:07:13.337] DEBUG[29375] jitterbuf.c: head 7272, tail 7292, ts 3788 [2012-03-07 20:07:13.337] DEBUG[29375] jitterbuf.c: head 7272, tail 7312, ts 3808 [2012-03-07 20:07:13.387] DEBUG[29379] jitterbuf.c: head 7332, tail 7332, ts 3828 [2012-03-07 20:07:13.387] DEBUG[29379] jitterbuf.c: head 7332, tail 7352, ts 3848 [2012-03-07 20:07:13.387] DEBUG[29379] jitterbuf.c: head 7332, tail 7372, ts 3868 [2012-03-07 20:07:13.436] DEBUG[29382] jitterbuf.c: head 7372, tail 7392, ts 3888 [2012-03-07 20:07:13.436] DEBUG[29382] jitterbuf.c: head 7372, tail 7412, ts 3908 [2012-03-07 20:07:13.487] DEBUG[29376] jitterbuf.c: head 7432, tail 7432, ts 3928 [2012-03-07 20:07:13.487] DEBUG[29376] jitterbuf.c: head 7432, tail 7452, ts 3948 [2012-03-07 20:07:13.487] DEBUG[29376] jitterbuf.c: head 7432, tail 7472, ts 3968 [2012-03-07 20:07:13.536] DEBUG[29379] jitterbuf.c: head 7472, tail 7492, ts 3988 [2012-03-07 20:07:13.536] DEBUG[29379] jitterbuf.c: head 7472, tail 7512, ts 4008 [2012-03-07 20:07:13.587] DEBUG[29383] jitterbuf.c: head 7532, tail 7532, ts 4028 [2012-03-07 20:07:13.587] DEBUG[29383] jitterbuf.c: head 7532, tail 7552, ts 4048 [2012-03-07 20:07:13.587] DEBUG[29383] jitterbuf.c: head 7532, tail 7572, ts 4068 [2012-03-07 20:07:13.636] DEBUG[29376] jitterbuf.c: head 7572, tail 7592, ts 4088 [2012-03-07 20:07:13.636] DEBUG[29376] jitterbuf.c: head 7572, tail 7612, ts 4108 [2012-03-07 20:07:13.687] DEBUG[29380] jitterbuf.c: head 7632, tail 7632, ts 4128 [2012-03-07 20:07:13.687] DEBUG[29380] jitterbuf.c: head 7632, tail 7652, ts 4148 [2012-03-07 20:07:13.687] DEBUG[29380] jitterbuf.c: head 7632, tail 7672, ts 4168 [2012-03-07 20:07:13.736] DEBUG[29383] jitterbuf.c: head 7672, tail 7692, ts 4188 [2012-03-07 20:07:13.736] DEBUG[29383] jitterbuf.c: head 7672, tail 7712, ts 4208 [2012-03-07 20:07:13.787] DEBUG[29377] jitterbuf.c: head 7732, tail 7732, ts 4228 [2012-03-07 20:07:13.787] DEBUG[29377] jitterbuf.c: head 7732, tail 7752, ts 4248 [2012-03-07 20:07:13.787] DEBUG[29377] jitterbuf.c: head 7732, tail 7772, ts 4268 [2012-03-07 20:07:13.836] DEBUG[29380] jitterbuf.c: head 7772, tail 7792, ts 4288 [2012-03-07 20:07:13.836] DEBUG[29380] jitterbuf.c: head 7772, tail 7812, ts 4308 [2012-03-07 20:07:13.887] DEBUG[29374] jitterbuf.c: head 7832, tail 7832, ts 4328 [2012-03-07 20:07:13.887] DEBUG[29374] jitterbuf.c: head 7832, tail 7852, ts 4348 [2012-03-07 20:07:13.887] DEBUG[29374] jitterbuf.c: head 7832, tail 7872, ts 4368 [2012-03-07 20:07:13.936] DEBUG[29377] jitterbuf.c: head 7872, tail 7892, ts 4388 [2012-03-07 20:07:13.936] DEBUG[29377] jitterbuf.c: head 7872, tail 7912, ts 4408 [2012-03-07 20:07:13.987] DEBUG[29381] jitterbuf.c: head 7932, tail 7932, ts 4428 [2012-03-07 20:07:13.987] DEBUG[29381] jitterbuf.c: head 7932, tail 7952, ts 4448 [2012-03-07 20:07:13.987] DEBUG[29381] jitterbuf.c: head 7932, tail 7972, ts 4468 [2012-03-07 20:07:14.036] DEBUG[29374] jitterbuf.c: head 7972, tail 7992, ts 4488 [2012-03-07 20:07:14.036] DEBUG[29374] jitterbuf.c: head 7972, tail 8012, ts 4508 [2012-03-07 20:07:14.087] DEBUG[29378] jitterbuf.c: head 8032, tail 8032, ts 4528 [2012-03-07 20:07:14.087] DEBUG[29378] jitterbuf.c: head 8032, tail 8052, ts 4548 [2012-03-07 20:07:14.087] DEBUG[29378] jitterbuf.c: head 8032, tail 8072, ts 4568 [2012-03-07 20:07:14.136] DEBUG[29381] jitterbuf.c: head 8072, tail 8092, ts 4588 [2012-03-07 20:07:14.136] DEBUG[29381] jitterbuf.c: head 8072, tail 8112, ts 4608 [2012-03-07 20:07:14.187] DEBUG[29375] jitterbuf.c: head 8132, tail 8132, ts 4628 [2012-03-07 20:07:14.187] DEBUG[29375] jitterbuf.c: head 8132, tail 8152, ts 4648 [2012-03-07 20:07:14.187] DEBUG[29375] jitterbuf.c: head 8132, tail 8172, ts 4668 [2012-03-07 20:07:14.236] DEBUG[29378] jitterbuf.c: head 8172, tail 8192, ts 4688 [2012-03-07 20:07:14.236] DEBUG[29378] jitterbuf.c: head 8172, tail 8212, ts 4708 [2012-03-07 20:07:14.286] DEBUG[29382] jitterbuf.c: head 8232, tail 8232, ts 4728 [2012-03-07 20:07:14.286] DEBUG[29382] jitterbuf.c: head 8232, tail 8252, ts 4748 [2012-03-07 20:07:14.286] DEBUG[29382] jitterbuf.c: head 8232, tail 8272, ts 4768 [2012-03-07 20:07:14.336] DEBUG[29375] jitterbuf.c: head 8272, tail 8292, ts 4788 [2012-03-07 20:07:14.336] DEBUG[29375] jitterbuf.c: head 8272, tail 8312, ts 4808 [2012-03-07 20:07:14.387] DEBUG[29379] jitterbuf.c: head 8332, tail 8332, ts 4828 [2012-03-07 20:07:14.387] DEBUG[29379] jitterbuf.c: head 8332, tail 8352, ts 4848 [2012-03-07 20:07:14.387] DEBUG[29379] jitterbuf.c: head 8332, tail 8372, ts 4868 [2012-03-07 20:07:14.436] DEBUG[29382] jitterbuf.c: head 8372, tail 8392, ts 4888 [2012-03-07 20:07:14.436] DEBUG[29382] jitterbuf.c: head 8372, tail 8412, ts 4908 [2012-03-07 20:07:14.487] DEBUG[29376] jitterbuf.c: head 8432, tail 8432, ts 4928 [2012-03-07 20:07:14.487] DEBUG[29376] jitterbuf.c: head 8432, tail 8452, ts 4948 [2012-03-07 20:07:14.487] DEBUG[29376] jitterbuf.c: head 8432, tail 8472, ts 4968 [2012-03-07 20:07:14.536] DEBUG[29379] jitterbuf.c: head 8472, tail 8492, ts 4988 [2012-03-07 20:07:14.536] DEBUG[29379] jitterbuf.c: head 8472, tail 8512, ts 5008 [2012-03-07 20:07:14.587] DEBUG[29383] jitterbuf.c: head 8532, tail 8532, ts 5028 [2012-03-07 20:07:14.587] DEBUG[29383] jitterbuf.c: head 8532, tail 8552, ts 5048 [2012-03-07 20:07:14.587] DEBUG[29383] jitterbuf.c: head 8532, tail 8572, ts 5068 [2012-03-07 20:07:14.637] DEBUG[29376] jitterbuf.c: head 8572, tail 8592, ts 5088 [2012-03-07 20:07:14.637] DEBUG[29376] jitterbuf.c: head 8572, tail 8612, ts 5108 [2012-03-07 20:07:14.688] DEBUG[29380] jitterbuf.c: head 8632, tail 8632, ts 5128 [2012-03-07 20:07:14.688] DEBUG[29380] jitterbuf.c: head 8632, tail 8652, ts 5148 [2012-03-07 20:07:14.688] DEBUG[29380] jitterbuf.c: head 8632, tail 8672, ts 5168 [2012-03-07 20:07:14.736] DEBUG[29383] jitterbuf.c: head 8652, tail 8692, ts 5188 [2012-03-07 20:07:14.736] DEBUG[29383] jitterbuf.c: head 8652, tail 8712, ts 5208 [2012-03-07 20:07:14.759] VERBOSE[29375] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 012 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:14.759] VERBOSE[29375] chan_iax2.c: Timestamp: 08744ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:14.759] DEBUG[29375] chan_iax2.c: Received packet 12, (4, 20) [2012-03-07 20:07:14.759] VERBOSE[29375] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 013 Type: IAX Subclass: ACK [2012-03-07 20:07:14.759] VERBOSE[29375] chan_iax2.c: Timestamp: 08744ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:14.759] DEBUG[29375] jitterbuf.c: head 8672, tail 8732, ts 8744 [2012-03-07 20:07:14.759] DEBUG[29375] jitterbuf.c: Adding non VOICE frame to jb with ts 8744) [2012-03-07 20:07:14.759] DEBUG[29375] chan_iax2.c: For call=1707, set last=8744 [2012-03-07 20:07:14.761] VERBOSE[29376] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 013 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:14.761] VERBOSE[29376] chan_iax2.c: Timestamp: 08747ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:14.761] DEBUG[29376] chan_iax2.c: Received packet 13, (4, 20) [2012-03-07 20:07:14.761] VERBOSE[29376] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 014 Type: IAX Subclass: ACK [2012-03-07 20:07:14.761] VERBOSE[29376] chan_iax2.c: Timestamp: 08747ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:14.761] DEBUG[29376] jitterbuf.c: head 8672, tail 12268, ts 8747 [2012-03-07 20:07:14.761] DEBUG[29376] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:14.761] DEBUG[29376] chan_iax2.c: For call=1707, set last=8747 [2012-03-07 20:07:14.762] VERBOSE[29377] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 014 ISeqno: 009 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:14.762] VERBOSE[29377] chan_iax2.c: Timestamp: 08750ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:14.762] DEBUG[29377] chan_iax2.c: Received packet 14, (4, 20) [2012-03-07 20:07:14.762] VERBOSE[29377] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 009 ISeqno: 015 Type: IAX Subclass: ACK [2012-03-07 20:07:14.762] VERBOSE[29377] chan_iax2.c: Timestamp: 08750ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:14.762] DEBUG[29377] jitterbuf.c: head 8672, tail 12268, ts 8750 [2012-03-07 20:07:14.762] DEBUG[29377] chan_iax2.c: For call=1707, set last=8750 [2012-03-07 20:07:14.787] DEBUG[29380] jitterbuf.c: head 8712, tail 12268, ts 5228 [2012-03-07 20:07:14.787] DEBUG[29380] jitterbuf.c: head 8712, tail 12268, ts 8780 [2012-03-07 20:07:14.839] DEBUG[29383] jitterbuf.c: head 12268, tail 12268, ts 8800 [2012-03-07 20:07:14.839] DEBUG[29383] jitterbuf.c: head 12268, tail 12268, ts 8820 [2012-03-07 20:07:14.839] DEBUG[29383] jitterbuf.c: head 12268, tail 12268, ts 8840 [2012-03-07 20:07:14.888] DEBUG[29377] jitterbuf.c: head 12268, tail 12268, ts 8860 [2012-03-07 20:07:14.888] WARNING[29377] chan_iax2.c: Resyncing the jb. last_delay -20, this delay -3520, threshold 1082, new offset -4 [2012-03-07 20:07:14.888] DEBUG[29377] jitterbuf.c: head 8864, tail 12268, ts 8880 [2012-03-07 20:07:14.888] DEBUG[29377] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:14.939] DEBUG[29380] jitterbuf.c: head 8864, tail 12268, ts 8900 [2012-03-07 20:07:14.939] DEBUG[29380] jitterbuf.c: head 8864, tail 12268, ts 8920 [2012-03-07 20:07:14.939] DEBUG[29380] jitterbuf.c: head 8864, tail 12268, ts 8940 [2012-03-07 20:07:14.987] DEBUG[29374] jitterbuf.c: head 12268, tail 12268, ts 8960 [2012-03-07 20:07:14.987] DEBUG[29374] jitterbuf.c: head 8964, tail 12268, ts 8980 [2012-03-07 20:07:14.987] DEBUG[29374] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.039] DEBUG[29378] jitterbuf.c: head 8964, tail 12268, ts 9000 [2012-03-07 20:07:15.039] DEBUG[29378] jitterbuf.c: head 8964, tail 12268, ts 9020 [2012-03-07 20:07:15.039] DEBUG[29378] jitterbuf.c: head 8964, tail 12268, ts 9040 [2012-03-07 20:07:15.106] DEBUG[29382] jitterbuf.c: head 12268, tail 12268, ts 9060 [2012-03-07 20:07:15.106] DEBUG[29382] jitterbuf.c: head 9064, tail 12268, ts 9080 [2012-03-07 20:07:15.106] DEBUG[29382] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.141] DEBUG[29375] jitterbuf.c: head 9064, tail 12268, ts 9100 [2012-03-07 20:07:15.141] DEBUG[29375] jitterbuf.c: head 9064, tail 12268, ts 9120 [2012-03-07 20:07:15.141] DEBUG[29375] jitterbuf.c: head 9064, tail 12268, ts 9140 [2012-03-07 20:07:15.191] DEBUG[29378] jitterbuf.c: head 12268, tail 12268, ts 9160 [2012-03-07 20:07:15.191] DEBUG[29378] jitterbuf.c: head 9164, tail 12268, ts 9180 [2012-03-07 20:07:15.191] DEBUG[29378] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.241] DEBUG[29382] jitterbuf.c: head 9164, tail 12268, ts 9200 [2012-03-07 20:07:15.241] DEBUG[29382] jitterbuf.c: head 9164, tail 12268, ts 9220 [2012-03-07 20:07:15.241] DEBUG[29382] jitterbuf.c: head 9164, tail 12268, ts 9240 [2012-03-07 20:07:15.288] DEBUG[29375] jitterbuf.c: head 12268, tail 12268, ts 9260 [2012-03-07 20:07:15.288] DEBUG[29375] jitterbuf.c: head 9264, tail 12268, ts 9280 [2012-03-07 20:07:15.288] DEBUG[29375] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.340] DEBUG[29379] jitterbuf.c: head 9264, tail 12268, ts 9300 [2012-03-07 20:07:15.340] DEBUG[29379] jitterbuf.c: head 9264, tail 12268, ts 9320 [2012-03-07 20:07:15.340] DEBUG[29379] jitterbuf.c: head 9264, tail 12268, ts 9340 [2012-03-07 20:07:15.389] DEBUG[29382] jitterbuf.c: head 12268, tail 12268, ts 9360 [2012-03-07 20:07:15.389] DEBUG[29382] jitterbuf.c: head 9364, tail 12268, ts 9380 [2012-03-07 20:07:15.389] DEBUG[29382] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.440] DEBUG[29376] jitterbuf.c: head 9364, tail 12268, ts 9400 [2012-03-07 20:07:15.440] DEBUG[29376] jitterbuf.c: head 9364, tail 12268, ts 9420 [2012-03-07 20:07:15.440] DEBUG[29376] jitterbuf.c: head 9364, tail 12268, ts 9440 [2012-03-07 20:07:15.489] DEBUG[29379] jitterbuf.c: head 12268, tail 12268, ts 9460 [2012-03-07 20:07:15.489] DEBUG[29379] jitterbuf.c: head 9464, tail 12268, ts 9480 [2012-03-07 20:07:15.489] DEBUG[29379] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.541] DEBUG[29383] jitterbuf.c: head 9464, tail 12268, ts 9500 [2012-03-07 20:07:15.541] DEBUG[29383] jitterbuf.c: head 9464, tail 12268, ts 9520 [2012-03-07 20:07:15.541] DEBUG[29383] jitterbuf.c: head 9464, tail 12268, ts 9540 [2012-03-07 20:07:15.586] DEBUG[29376] jitterbuf.c: head 12268, tail 12268, ts 9560 [2012-03-07 20:07:15.587] DEBUG[29376] jitterbuf.c: head 9564, tail 12268, ts 9580 [2012-03-07 20:07:15.587] DEBUG[29376] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.638] DEBUG[29380] jitterbuf.c: head 9564, tail 12268, ts 9600 [2012-03-07 20:07:15.638] DEBUG[29380] jitterbuf.c: head 9564, tail 12268, ts 9620 [2012-03-07 20:07:15.638] DEBUG[29380] jitterbuf.c: head 9564, tail 12268, ts 9640 [2012-03-07 20:07:15.692] DEBUG[29383] jitterbuf.c: head 12268, tail 12268, ts 9660 [2012-03-07 20:07:15.692] DEBUG[29383] jitterbuf.c: head 9664, tail 12268, ts 9680 [2012-03-07 20:07:15.692] DEBUG[29383] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.753] DEBUG[29377] jitterbuf.c: head 9664, tail 12268, ts 9700 [2012-03-07 20:07:15.753] DEBUG[29377] jitterbuf.c: head 9664, tail 12268, ts 9720 [2012-03-07 20:07:15.753] DEBUG[29377] jitterbuf.c: head 9664, tail 12268, ts 9740 [2012-03-07 20:07:15.789] DEBUG[29380] jitterbuf.c: head 12268, tail 12268, ts 9760 [2012-03-07 20:07:15.789] DEBUG[29380] jitterbuf.c: head 9764, tail 12268, ts 9780 [2012-03-07 20:07:15.789] DEBUG[29380] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.842] DEBUG[29374] jitterbuf.c: head 9764, tail 12268, ts 9800 [2012-03-07 20:07:15.842] DEBUG[29374] jitterbuf.c: head 9764, tail 12268, ts 9820 [2012-03-07 20:07:15.842] DEBUG[29374] jitterbuf.c: head 9764, tail 12268, ts 9840 [2012-03-07 20:07:15.889] DEBUG[29377] jitterbuf.c: head 12268, tail 12268, ts 9860 [2012-03-07 20:07:15.889] DEBUG[29377] jitterbuf.c: head 9864, tail 12268, ts 9880 [2012-03-07 20:07:15.889] DEBUG[29377] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:15.941] DEBUG[29381] jitterbuf.c: head 9864, tail 12268, ts 9900 [2012-03-07 20:07:15.941] DEBUG[29381] jitterbuf.c: head 9864, tail 12268, ts 9920 [2012-03-07 20:07:15.941] DEBUG[29381] jitterbuf.c: head 9864, tail 12268, ts 9940 [2012-03-07 20:07:15.989] DEBUG[29374] jitterbuf.c: head 12268, tail 12268, ts 9960 [2012-03-07 20:07:15.989] DEBUG[29374] jitterbuf.c: head 9964, tail 12268, ts 9980 [2012-03-07 20:07:15.989] DEBUG[29374] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.027] VERBOSE[29377] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 015 ISeqno: 009 Type: IAX Subclass: LAGRQ [2012-03-07 20:07:16.027] VERBOSE[29377] chan_iax2.c: Timestamp: 10041ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:16.027] DEBUG[29377] chan_iax2.c: Received packet 15, (6, 11) [2012-03-07 20:07:16.027] DEBUG[29377] chan_iax2.c: IAX subclass 11 received [2012-03-07 20:07:16.027] DEBUG[29377] chan_iax2.c: For call=1707, set last=10041 [2012-03-07 20:07:16.027] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 009 ISeqno: 016 Type: IAX Subclass: LAGRP [2012-03-07 20:07:16.027] VERBOSE[29372] chan_iax2.c: Timestamp: 10041ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:16.041] DEBUG[29379] jitterbuf.c: head 9964, tail 12268, ts 10000 [2012-03-07 20:07:16.041] DEBUG[29379] jitterbuf.c: head 9964, tail 12268, ts 10020 [2012-03-07 20:07:16.041] DEBUG[29379] jitterbuf.c: head 9964, tail 12268, ts 10040 [2012-03-07 20:07:16.047] VERBOSE[29380] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 016 ISeqno: 010 Type: IAX Subclass: ACK [2012-03-07 20:07:16.047] VERBOSE[29380] chan_iax2.c: Timestamp: 10041ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:16.047] DEBUG[29380] chan_iax2.c: Received packet 16, (6, 4) [2012-03-07 20:07:16.047] DEBUG[29380] chan_iax2.c: Cancelling transmission of packet 9 [2012-03-07 20:07:16.047] DEBUG[29380] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:16.062] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 010 ISeqno: 016 Type: IAX Subclass: LAGRQ [2012-03-07 20:07:16.062] VERBOSE[29372] chan_iax2.c: Timestamp: 10017ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:16.083] VERBOSE[29374] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 016 ISeqno: 011 Type: IAX Subclass: LAGRP [2012-03-07 20:07:16.083] VERBOSE[29374] chan_iax2.c: Timestamp: 10017ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:16.083] DEBUG[29374] chan_iax2.c: Received packet 16, (6, 12) [2012-03-07 20:07:16.083] DEBUG[29374] chan_iax2.c: Cancelling transmission of packet 10 [2012-03-07 20:07:16.083] DEBUG[29374] chan_iax2.c: IAX subclass 12 received [2012-03-07 20:07:16.083] DEBUG[29374] chan_iax2.c: Peer 10.xxx.xxx.xx2 lag measured as 20ms [2012-03-07 20:07:16.083] VERBOSE[29374] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 011 ISeqno: 017 Type: IAX Subclass: ACK [2012-03-07 20:07:16.083] VERBOSE[29374] chan_iax2.c: Timestamp: 10017ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:16.086] DEBUG[29375] jitterbuf.c: head 12268, tail 12268, ts 10060 [2012-03-07 20:07:16.086] DEBUG[29375] jitterbuf.c: head 10064, tail 12268, ts 10080 [2012-03-07 20:07:16.086] DEBUG[29375] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.138] DEBUG[29379] jitterbuf.c: head 10064, tail 12268, ts 10100 [2012-03-07 20:07:16.138] DEBUG[29379] jitterbuf.c: head 10064, tail 12268, ts 10120 [2012-03-07 20:07:16.138] DEBUG[29379] jitterbuf.c: head 10064, tail 12268, ts 10140 [2012-03-07 20:07:16.189] DEBUG[29382] jitterbuf.c: head 12268, tail 12268, ts 10160 [2012-03-07 20:07:16.189] DEBUG[29382] jitterbuf.c: head 10164, tail 12268, ts 10180 [2012-03-07 20:07:16.189] DEBUG[29382] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.241] DEBUG[29376] jitterbuf.c: head 10164, tail 12268, ts 10200 [2012-03-07 20:07:16.241] DEBUG[29376] jitterbuf.c: head 10164, tail 12268, ts 10220 [2012-03-07 20:07:16.241] DEBUG[29376] jitterbuf.c: head 10164, tail 12268, ts 10240 [2012-03-07 20:07:16.289] DEBUG[29379] jitterbuf.c: head 12268, tail 12268, ts 10260 [2012-03-07 20:07:16.289] DEBUG[29379] jitterbuf.c: head 10264, tail 12268, ts 10280 [2012-03-07 20:07:16.289] DEBUG[29379] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.341] DEBUG[29383] jitterbuf.c: head 10264, tail 12268, ts 10300 [2012-03-07 20:07:16.341] DEBUG[29383] jitterbuf.c: head 10264, tail 12268, ts 10320 [2012-03-07 20:07:16.341] DEBUG[29383] jitterbuf.c: head 10264, tail 12268, ts 10340 [2012-03-07 20:07:16.389] DEBUG[29376] jitterbuf.c: head 12268, tail 12268, ts 10360 [2012-03-07 20:07:16.390] DEBUG[29376] jitterbuf.c: head 10364, tail 12268, ts 10380 [2012-03-07 20:07:16.390] DEBUG[29376] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.441] DEBUG[29380] jitterbuf.c: head 10364, tail 12268, ts 10400 [2012-03-07 20:07:16.441] DEBUG[29380] jitterbuf.c: head 10364, tail 12268, ts 10420 [2012-03-07 20:07:16.441] DEBUG[29380] jitterbuf.c: head 10364, tail 12268, ts 10440 [2012-03-07 20:07:16.489] DEBUG[29383] jitterbuf.c: head 12268, tail 12268, ts 10460 [2012-03-07 20:07:16.489] DEBUG[29383] jitterbuf.c: head 10464, tail 12268, ts 10480 [2012-03-07 20:07:16.489] DEBUG[29383] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.541] DEBUG[29377] jitterbuf.c: head 10464, tail 12268, ts 10500 [2012-03-07 20:07:16.541] DEBUG[29377] jitterbuf.c: head 10464, tail 12268, ts 10520 [2012-03-07 20:07:16.541] DEBUG[29377] jitterbuf.c: head 10464, tail 12268, ts 10540 [2012-03-07 20:07:16.589] DEBUG[29380] jitterbuf.c: head 12268, tail 12268, ts 10560 [2012-03-07 20:07:16.589] DEBUG[29380] jitterbuf.c: head 10564, tail 12268, ts 10580 [2012-03-07 20:07:16.589] DEBUG[29380] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.641] DEBUG[29374] jitterbuf.c: head 10564, tail 12268, ts 10600 [2012-03-07 20:07:16.641] DEBUG[29374] jitterbuf.c: head 10564, tail 12268, ts 10620 [2012-03-07 20:07:16.641] DEBUG[29374] jitterbuf.c: head 10564, tail 12268, ts 10640 [2012-03-07 20:07:16.689] DEBUG[29377] jitterbuf.c: head 12268, tail 12268, ts 10660 [2012-03-07 20:07:16.689] DEBUG[29377] jitterbuf.c: head 10664, tail 12268, ts 10680 [2012-03-07 20:07:16.689] DEBUG[29377] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.741] DEBUG[29381] jitterbuf.c: head 10664, tail 12268, ts 10700 [2012-03-07 20:07:16.741] DEBUG[29381] jitterbuf.c: head 10664, tail 12268, ts 10720 [2012-03-07 20:07:16.741] DEBUG[29381] jitterbuf.c: head 10664, tail 12268, ts 10740 [2012-03-07 20:07:16.789] DEBUG[29374] jitterbuf.c: head 12268, tail 12268, ts 10760 [2012-03-07 20:07:16.789] DEBUG[29374] jitterbuf.c: head 10764, tail 12268, ts 10780 [2012-03-07 20:07:16.789] DEBUG[29374] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.840] DEBUG[29378] jitterbuf.c: head 10764, tail 12268, ts 10800 [2012-03-07 20:07:16.840] DEBUG[29378] jitterbuf.c: head 10764, tail 12268, ts 10820 [2012-03-07 20:07:16.840] DEBUG[29378] jitterbuf.c: head 10764, tail 12268, ts 10840 [2012-03-07 20:07:16.889] DEBUG[29381] jitterbuf.c: head 12268, tail 12268, ts 10860 [2012-03-07 20:07:16.889] DEBUG[29381] jitterbuf.c: head 10864, tail 12268, ts 10880 [2012-03-07 20:07:16.889] DEBUG[29381] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:16.941] DEBUG[29375] jitterbuf.c: head 10864, tail 12268, ts 10900 [2012-03-07 20:07:16.941] DEBUG[29375] jitterbuf.c: head 10864, tail 12268, ts 10920 [2012-03-07 20:07:16.941] DEBUG[29375] jitterbuf.c: head 10864, tail 12268, ts 10940 [2012-03-07 20:07:16.989] DEBUG[29378] jitterbuf.c: head 12268, tail 12268, ts 10960 [2012-03-07 20:07:16.989] DEBUG[29378] jitterbuf.c: head 10964, tail 12268, ts 10980 [2012-03-07 20:07:16.989] DEBUG[29378] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:17.040] DEBUG[29382] jitterbuf.c: head 10964, tail 12268, ts 11000 [2012-03-07 20:07:17.040] DEBUG[29382] jitterbuf.c: head 10964, tail 12268, ts 11020 [2012-03-07 20:07:17.040] DEBUG[29382] jitterbuf.c: head 10964, tail 12268, ts 11040 [2012-03-07 20:07:17.089] DEBUG[29375] jitterbuf.c: head 12268, tail 12268, ts 11060 [2012-03-07 20:07:17.089] DEBUG[29375] jitterbuf.c: head 11064, tail 12268, ts 11080 [2012-03-07 20:07:17.089] DEBUG[29375] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:17.140] DEBUG[29379] jitterbuf.c: head 11064, tail 12268, ts 11100 [2012-03-07 20:07:17.140] DEBUG[29379] jitterbuf.c: head 11064, tail 12268, ts 11120 [2012-03-07 20:07:17.140] DEBUG[29379] jitterbuf.c: head 11064, tail 12268, ts 11140 [2012-03-07 20:07:17.188] DEBUG[29382] jitterbuf.c: head 12268, tail 12268, ts 11160 [2012-03-07 20:07:17.188] DEBUG[29382] jitterbuf.c: head 11164, tail 12268, ts 11180 [2012-03-07 20:07:17.188] DEBUG[29382] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:17.239] DEBUG[29376] jitterbuf.c: head 11164, tail 12268, ts 11200 [2012-03-07 20:07:17.239] DEBUG[29376] jitterbuf.c: head 11164, tail 12268, ts 11220 [2012-03-07 20:07:17.239] DEBUG[29376] jitterbuf.c: head 11164, tail 12268, ts 11240 [2012-03-07 20:07:17.290] DEBUG[29379] jitterbuf.c: head 12268, tail 12268, ts 11260 [2012-03-07 20:07:17.290] DEBUG[29379] jitterbuf.c: head 11264, tail 12268, ts 11280 [2012-03-07 20:07:17.290] DEBUG[29379] jitterbuf.c: Attempting to exceed Jitterbuf max 1000 timeslots [2012-03-07 20:07:17.341] DEBUG[29383] jitterbuf.c: head 11264, tail 12268, ts 11300 [2012-03-07 20:07:17.341] DEBUG[29383] jitterbuf.c: head 11264, tail 12268, ts 11320 [2012-03-07 20:07:17.341] DEBUG[29383] jitterbuf.c: head 11264, tail 12268, ts 11340 [2012-03-07 20:07:17.389] DEBUG[29376] jitterbuf.c: head 12268, tail 12268, ts 11360 [2012-03-07 20:07:17.389] DEBUG[29376] jitterbuf.c: head 11364, tail 12268, ts 11380 [2012-03-07 20:07:17.441] DEBUG[29380] jitterbuf.c: head 11364, tail 12268, ts 11400 [2012-03-07 20:07:17.441] DEBUG[29380] jitterbuf.c: head 11364, tail 12268, ts 11420 [2012-03-07 20:07:17.441] DEBUG[29380] jitterbuf.c: head 11364, tail 12268, ts 11440 [2012-03-07 20:07:17.489] DEBUG[29383] jitterbuf.c: head 11404, tail 12268, ts 11460 [2012-03-07 20:07:17.489] DEBUG[29383] jitterbuf.c: head 11404, tail 12268, ts 11480 [2012-03-07 20:07:17.541] DEBUG[29377] jitterbuf.c: head 11464, tail 12268, ts 11500 [2012-03-07 20:07:17.541] DEBUG[29377] jitterbuf.c: head 11464, tail 12268, ts 11520 [2012-03-07 20:07:17.541] DEBUG[29377] jitterbuf.c: head 11464, tail 12268, ts 11540 [2012-03-07 20:07:17.589] DEBUG[29380] jitterbuf.c: head 11504, tail 12268, ts 11560 [2012-03-07 20:07:17.589] DEBUG[29380] jitterbuf.c: head 11504, tail 12268, ts 11580 [2012-03-07 20:07:17.640] DEBUG[29374] jitterbuf.c: head 11564, tail 12268, ts 11600 [2012-03-07 20:07:17.640] DEBUG[29374] jitterbuf.c: head 11564, tail 12268, ts 11620 [2012-03-07 20:07:17.640] DEBUG[29374] jitterbuf.c: head 11564, tail 12268, ts 11640 [2012-03-07 20:07:17.689] DEBUG[29377] jitterbuf.c: head 11604, tail 12268, ts 11660 [2012-03-07 20:07:17.689] DEBUG[29377] jitterbuf.c: head 11604, tail 12268, ts 11680 [2012-03-07 20:07:17.740] DEBUG[29381] jitterbuf.c: head 11664, tail 12268, ts 11700 [2012-03-07 20:07:17.740] DEBUG[29381] jitterbuf.c: head 11664, tail 12268, ts 11720 [2012-03-07 20:07:17.740] DEBUG[29381] jitterbuf.c: head 11664, tail 12268, ts 11740 [2012-03-07 20:07:17.789] DEBUG[29374] jitterbuf.c: head 11704, tail 12268, ts 11760 [2012-03-07 20:07:17.789] DEBUG[29374] jitterbuf.c: head 11704, tail 12268, ts 11780 [2012-03-07 20:07:17.841] DEBUG[29378] jitterbuf.c: head 11764, tail 12268, ts 11800 [2012-03-07 20:07:17.841] DEBUG[29378] jitterbuf.c: head 11764, tail 12268, ts 11820 [2012-03-07 20:07:17.841] DEBUG[29378] jitterbuf.c: head 11764, tail 12268, ts 11840 [2012-03-07 20:07:17.889] DEBUG[29381] jitterbuf.c: head 11804, tail 12268, ts 11860 [2012-03-07 20:07:17.889] DEBUG[29381] jitterbuf.c: head 11804, tail 12268, ts 11880 [2012-03-07 20:07:17.941] DEBUG[29375] jitterbuf.c: head 11864, tail 12268, ts 11900 [2012-03-07 20:07:17.941] DEBUG[29375] jitterbuf.c: head 11864, tail 12268, ts 11920 [2012-03-07 20:07:17.941] DEBUG[29375] jitterbuf.c: head 11864, tail 12268, ts 11940 [2012-03-07 20:07:17.989] DEBUG[29378] jitterbuf.c: head 11904, tail 12268, ts 11960 [2012-03-07 20:07:17.989] DEBUG[29378] jitterbuf.c: head 11904, tail 12268, ts 11980 [2012-03-07 20:07:18.041] DEBUG[29383] jitterbuf.c: head 11964, tail 12268, ts 12000 [2012-03-07 20:07:18.041] DEBUG[29383] jitterbuf.c: head 11964, tail 12268, ts 12020 [2012-03-07 20:07:18.041] DEBUG[29383] jitterbuf.c: head 11964, tail 12268, ts 12040 [2012-03-07 20:07:18.089] DEBUG[29377] jitterbuf.c: head 12004, tail 12268, ts 12060 [2012-03-07 20:07:18.089] DEBUG[29377] jitterbuf.c: head 12004, tail 12268, ts 12080 [2012-03-07 20:07:18.141] DEBUG[29381] jitterbuf.c: head 12064, tail 12268, ts 12100 [2012-03-07 20:07:18.141] DEBUG[29381] jitterbuf.c: head 12064, tail 12268, ts 12120 [2012-03-07 20:07:18.141] DEBUG[29381] jitterbuf.c: head 12064, tail 12268, ts 12140 [2012-03-07 20:07:18.186] DEBUG[29374] jitterbuf.c: head 12104, tail 12268, ts 12160 [2012-03-07 20:07:18.186] DEBUG[29374] jitterbuf.c: head 12104, tail 12268, ts 12180 [2012-03-07 20:07:18.237] DEBUG[29377] jitterbuf.c: head 12144, tail 12268, ts 12200 [2012-03-07 20:07:18.237] DEBUG[29377] jitterbuf.c: head 12144, tail 12268, ts 12220 [2012-03-07 20:07:18.237] DEBUG[29377] jitterbuf.c: head 12144, tail 12268, ts 12240 [2012-03-07 20:07:18.286] DEBUG[29381] jitterbuf.c: head 12204, tail 12268, ts 12260 [2012-03-07 20:07:18.286] DEBUG[29381] jitterbuf.c: head 12204, tail 12268, ts 12280 [2012-03-07 20:07:18.337] DEBUG[29374] jitterbuf.c: head 12244, tail 12284, ts 12300 [2012-03-07 20:07:18.337] DEBUG[29374] jitterbuf.c: head 12244, tail 12304, ts 12320 [2012-03-07 20:07:18.337] DEBUG[29374] jitterbuf.c: head 12244, tail 12324, ts 12340 [2012-03-07 20:07:18.377] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:18.386] DEBUG[29379] jitterbuf.c: head 12304, tail 12344, ts 12360 [2012-03-07 20:07:18.386] DEBUG[29379] jitterbuf.c: head 12304, tail 12364, ts 12380 [2012-03-07 20:07:18.439] DEBUG[29383] jitterbuf.c: head 12364, tail 12384, ts 12400 [2012-03-07 20:07:18.439] DEBUG[29383] jitterbuf.c: head 12364, tail 12404, ts 12420 [2012-03-07 20:07:18.439] DEBUG[29383] jitterbuf.c: head 12364, tail 12424, ts 12440 [2012-03-07 20:07:18.489] DEBUG[29376] jitterbuf.c: head 12404, tail 12444, ts 12460 [2012-03-07 20:07:18.489] DEBUG[29376] jitterbuf.c: head 12404, tail 12464, ts 12480 [2012-03-07 20:07:18.540] DEBUG[29380] jitterbuf.c: head 12464, tail 12484, ts 12500 [2012-03-07 20:07:18.540] DEBUG[29380] jitterbuf.c: head 12464, tail 12504, ts 12520 [2012-03-07 20:07:18.540] DEBUG[29380] jitterbuf.c: head 12464, tail 12524, ts 12540 [2012-03-07 20:07:18.589] DEBUG[29383] jitterbuf.c: head 12504, tail 12544, ts 12560 [2012-03-07 20:07:18.589] DEBUG[29383] jitterbuf.c: head 12504, tail 12564, ts 12580 [2012-03-07 20:07:18.640] DEBUG[29377] jitterbuf.c: head 12564, tail 12584, ts 12600 [2012-03-07 20:07:18.640] DEBUG[29377] jitterbuf.c: head 12564, tail 12604, ts 12620 [2012-03-07 20:07:18.640] DEBUG[29377] jitterbuf.c: head 12564, tail 12624, ts 12640 [2012-03-07 20:07:18.689] DEBUG[29380] jitterbuf.c: head 12604, tail 12644, ts 12660 [2012-03-07 20:07:18.689] DEBUG[29380] jitterbuf.c: head 12604, tail 12664, ts 12680 [2012-03-07 20:07:18.741] DEBUG[29374] jitterbuf.c: head 12664, tail 12684, ts 12700 [2012-03-07 20:07:18.741] DEBUG[29374] jitterbuf.c: head 12664, tail 12704, ts 12720 [2012-03-07 20:07:18.741] DEBUG[29374] jitterbuf.c: head 12664, tail 12724, ts 12740 [2012-03-07 20:07:18.789] DEBUG[29377] jitterbuf.c: head 12704, tail 12744, ts 12760 [2012-03-07 20:07:18.789] DEBUG[29377] jitterbuf.c: head 12704, tail 12764, ts 12780 [2012-03-07 20:07:18.841] DEBUG[29381] jitterbuf.c: head 12764, tail 12784, ts 12800 [2012-03-07 20:07:18.841] DEBUG[29381] jitterbuf.c: head 12764, tail 12804, ts 12820 [2012-03-07 20:07:18.841] DEBUG[29381] jitterbuf.c: head 12764, tail 12824, ts 12840 [2012-03-07 20:07:18.889] DEBUG[29374] jitterbuf.c: head 12804, tail 12844, ts 12860 [2012-03-07 20:07:18.889] DEBUG[29374] jitterbuf.c: head 12804, tail 12864, ts 12880 [2012-03-07 20:07:18.940] DEBUG[29378] jitterbuf.c: head 12864, tail 12884, ts 12900 [2012-03-07 20:07:18.940] DEBUG[29378] jitterbuf.c: head 12864, tail 12904, ts 12920 [2012-03-07 20:07:18.940] DEBUG[29378] jitterbuf.c: head 12864, tail 12924, ts 12940 [2012-03-07 20:07:18.989] DEBUG[29381] jitterbuf.c: head 12904, tail 12944, ts 12960 [2012-03-07 20:07:18.989] DEBUG[29381] jitterbuf.c: head 12904, tail 12964, ts 12980 [2012-03-07 20:07:19.040] DEBUG[29375] jitterbuf.c: head 12964, tail 12984, ts 13000 [2012-03-07 20:07:19.040] DEBUG[29375] jitterbuf.c: head 12964, tail 13004, ts 13020 [2012-03-07 20:07:19.040] DEBUG[29375] jitterbuf.c: head 12964, tail 13024, ts 13040 [2012-03-07 20:07:19.084] VERBOSE[29378] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 017 ISeqno: 011 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:19.084] VERBOSE[29378] chan_iax2.c: Timestamp: 13083ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:19.084] DEBUG[29378] chan_iax2.c: Received packet 17, (4, 20) [2012-03-07 20:07:19.084] VERBOSE[29378] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 011 ISeqno: 018 Type: IAX Subclass: ACK [2012-03-07 20:07:19.084] VERBOSE[29378] chan_iax2.c: Timestamp: 13083ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:19.084] DEBUG[29378] jitterbuf.c: head 13004, tail 13044, ts 13083 [2012-03-07 20:07:19.084] DEBUG[29378] jitterbuf.c: Adding non VOICE frame to jb with ts 13083) [2012-03-07 20:07:19.084] DEBUG[29378] chan_iax2.c: For call=1707, set last=13083 [2012-03-07 20:07:19.086] VERBOSE[29379] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 018 ISeqno: 011 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:19.086] VERBOSE[29379] chan_iax2.c: Timestamp: 13086ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:19.086] DEBUG[29379] chan_iax2.c: Received packet 18, (4, 20) [2012-03-07 20:07:19.086] VERBOSE[29379] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 011 ISeqno: 019 Type: IAX Subclass: ACK [2012-03-07 20:07:19.086] VERBOSE[29379] chan_iax2.c: Timestamp: 13086ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:19.086] DEBUG[29379] jitterbuf.c: head 13004, tail 13087, ts 13086 [2012-03-07 20:07:19.086] DEBUG[29379] jitterbuf.c: Adding non VOICE frame to jb with ts 13086) [2012-03-07 20:07:19.086] DEBUG[29379] chan_iax2.c: For call=1707, set last=13086 [2012-03-07 20:07:19.087] VERBOSE[29380] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 019 ISeqno: 011 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:19.087] VERBOSE[29380] chan_iax2.c: Timestamp: 13089ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:19.087] DEBUG[29380] chan_iax2.c: Received packet 19, (4, 20) [2012-03-07 20:07:19.087] VERBOSE[29380] chan_iax2.c: Tx-Frame Retry[-01] -- OSeqno: 011 ISeqno: 020 Type: IAX Subclass: ACK [2012-03-07 20:07:19.087] VERBOSE[29380] chan_iax2.c: Timestamp: 13089ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:19.087] DEBUG[29380] jitterbuf.c: head 13004, tail 13090, ts 13089 [2012-03-07 20:07:19.087] DEBUG[29380] jitterbuf.c: Adding non VOICE frame to jb with ts 13089) [2012-03-07 20:07:19.088] DEBUG[29380] chan_iax2.c: For call=1707, set last=13089 [2012-03-07 20:07:19.094] DEBUG[29381] jitterbuf.c: head 13004, tail 13093, ts 13060 [2012-03-07 20:07:19.094] DEBUG[29381] jitterbuf.c: head 13004, tail 13093, ts 13080 [2012-03-07 20:07:19.198] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:19.198] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:19.198] DEBUG[29403] chan_dahdi.c: Requested indication 20 on channel DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:19.387] WARNING[29381] chan_iax2.c: Resyncing the jb. last_delay -15, this delay 8044, threshold 1080, new offset -8048 [2012-03-07 20:07:19.387] DEBUG[29381] jitterbuf.c: head 13362, tail 13362, ts 5334 [2012-03-07 20:07:19.436] DEBUG[29382] jitterbuf.c: head 13362, tail 13382, ts 5354 [2012-03-07 20:07:19.436] DEBUG[29382] jitterbuf.c: head 13362, tail 13402, ts 5374 [2012-03-07 20:07:19.487] DEBUG[29380] jitterbuf.c: head 13422, tail 13422, ts 5394 [2012-03-07 20:07:19.487] DEBUG[29380] jitterbuf.c: head 13422, tail 13442, ts 5414 [2012-03-07 20:07:19.487] DEBUG[29380] jitterbuf.c: head 13422, tail 13462, ts 5434 [2012-03-07 20:07:19.540] DEBUG[29374] jitterbuf.c: head 13462, tail 13482, ts 5454 [2012-03-07 20:07:19.540] DEBUG[29374] jitterbuf.c: head 13462, tail 13502, ts 5474 [2012-03-07 20:07:19.587] DEBUG[29377] jitterbuf.c: head 13502, tail 13522, ts 5494 [2012-03-07 20:07:19.587] DEBUG[29377] jitterbuf.c: head 13502, tail 13542, ts 5514 [2012-03-07 20:07:19.587] DEBUG[29377] jitterbuf.c: head 13502, tail 13562, ts 5534 [2012-03-07 20:07:19.636] DEBUG[29381] jitterbuf.c: head 13562, tail 13582, ts 5554 [2012-03-07 20:07:19.636] DEBUG[29381] jitterbuf.c: head 13562, tail 13602, ts 5574 [2012-03-07 20:07:19.687] DEBUG[29374] jitterbuf.c: head 13602, tail 13622, ts 5594 [2012-03-07 20:07:19.687] DEBUG[29374] jitterbuf.c: head 13602, tail 13642, ts 5614 [2012-03-07 20:07:19.687] DEBUG[29374] jitterbuf.c: head 13602, tail 13662, ts 5634 [2012-03-07 20:07:19.739] DEBUG[29378] jitterbuf.c: head 13662, tail 13682, ts 5654 [2012-03-07 20:07:19.739] DEBUG[29378] jitterbuf.c: head 13662, tail 13702, ts 5674 [2012-03-07 20:07:19.788] DEBUG[29381] jitterbuf.c: head 13702, tail 13722, ts 5694 [2012-03-07 20:07:19.788] DEBUG[29381] jitterbuf.c: head 13702, tail 13742, ts 5714 [2012-03-07 20:07:19.788] DEBUG[29381] jitterbuf.c: head 13702, tail 13762, ts 5734 [2012-03-07 20:07:19.836] DEBUG[29375] jitterbuf.c: head 13762, tail 13782, ts 5754 [2012-03-07 20:07:19.836] DEBUG[29375] jitterbuf.c: head 13762, tail 13802, ts 5774 [2012-03-07 20:07:19.887] DEBUG[29378] jitterbuf.c: head 13802, tail 13822, ts 5794 [2012-03-07 20:07:19.887] DEBUG[29378] jitterbuf.c: head 13802, tail 13842, ts 5814 [2012-03-07 20:07:19.887] DEBUG[29378] jitterbuf.c: head 13802, tail 13862, ts 5834 [2012-03-07 20:07:19.937] DEBUG[29382] jitterbuf.c: head 13862, tail 13882, ts 5854 [2012-03-07 20:07:19.937] DEBUG[29382] jitterbuf.c: head 13862, tail 13902, ts 5874 [2012-03-07 20:07:19.987] DEBUG[29375] jitterbuf.c: head 13902, tail 13922, ts 5894 [2012-03-07 20:07:19.987] DEBUG[29375] jitterbuf.c: head 13902, tail 13942, ts 5914 [2012-03-07 20:07:19.987] DEBUG[29375] jitterbuf.c: head 13902, tail 13962, ts 5934 [2012-03-07 20:07:20.036] DEBUG[29378] jitterbuf.c: head 13942, tail 13982, ts 5954 [2012-03-07 20:07:20.036] DEBUG[29378] jitterbuf.c: head 13942, tail 14002, ts 5974 [2012-03-07 20:07:20.087] DEBUG[29382] jitterbuf.c: head 14002, tail 14022, ts 5994 [2012-03-07 20:07:20.087] DEBUG[29382] jitterbuf.c: head 14002, tail 14042, ts 6014 [2012-03-07 20:07:20.087] DEBUG[29382] jitterbuf.c: head 14002, tail 14062, ts 6034 [2012-03-07 20:07:20.138] DEBUG[29376] jitterbuf.c: head 14062, tail 14082, ts 6054 [2012-03-07 20:07:20.138] DEBUG[29376] jitterbuf.c: head 14062, tail 14102, ts 6074 [2012-03-07 20:07:20.188] DEBUG[29379] jitterbuf.c: head 14102, tail 14122, ts 6094 [2012-03-07 20:07:20.188] DEBUG[29379] jitterbuf.c: head 14102, tail 14142, ts 6114 [2012-03-07 20:07:20.188] DEBUG[29379] jitterbuf.c: head 14102, tail 14162, ts 6134 [2012-03-07 20:07:20.236] DEBUG[29383] jitterbuf.c: head 14162, tail 14182, ts 6154 [2012-03-07 20:07:20.236] DEBUG[29383] jitterbuf.c: head 14162, tail 14202, ts 6174 [2012-03-07 20:07:20.287] DEBUG[29376] jitterbuf.c: head 14202, tail 14222, ts 6194 [2012-03-07 20:07:20.287] DEBUG[29376] jitterbuf.c: head 14202, tail 14242, ts 6214 [2012-03-07 20:07:20.287] DEBUG[29376] jitterbuf.c: head 14202, tail 14262, ts 6234 [2012-03-07 20:07:20.337] DEBUG[29380] jitterbuf.c: head 14262, tail 14282, ts 6254 [2012-03-07 20:07:20.337] DEBUG[29380] jitterbuf.c: head 14262, tail 14302, ts 6274 [2012-03-07 20:07:20.387] DEBUG[29383] jitterbuf.c: head 14302, tail 14322, ts 6294 [2012-03-07 20:07:20.387] DEBUG[29383] jitterbuf.c: head 14302, tail 14342, ts 6314 [2012-03-07 20:07:20.387] DEBUG[29383] jitterbuf.c: head 14302, tail 14362, ts 6334 [2012-03-07 20:07:20.436] DEBUG[29377] jitterbuf.c: head 14362, tail 14382, ts 6354 [2012-03-07 20:07:20.436] DEBUG[29377] jitterbuf.c: head 14362, tail 14402, ts 6374 [2012-03-07 20:07:20.487] DEBUG[29380] jitterbuf.c: head 14402, tail 14422, ts 6394 [2012-03-07 20:07:20.487] DEBUG[29380] jitterbuf.c: head 14402, tail 14442, ts 6414 [2012-03-07 20:07:20.488] DEBUG[29380] jitterbuf.c: head 14402, tail 14462, ts 6434 [2012-03-07 20:07:20.538] DEBUG[29374] jitterbuf.c: head 14462, tail 14482, ts 6454 [2012-03-07 20:07:20.538] DEBUG[29374] jitterbuf.c: head 14462, tail 14502, ts 6474 [2012-03-07 20:07:20.588] DEBUG[29377] jitterbuf.c: head 14502, tail 14522, ts 6494 [2012-03-07 20:07:20.588] DEBUG[29377] jitterbuf.c: head 14502, tail 14542, ts 6514 [2012-03-07 20:07:20.588] DEBUG[29377] jitterbuf.c: head 14502, tail 14562, ts 6534 [2012-03-07 20:07:20.637] DEBUG[29381] jitterbuf.c: head 14562, tail 14582, ts 6554 [2012-03-07 20:07:20.637] DEBUG[29381] jitterbuf.c: head 14562, tail 14602, ts 6574 [2012-03-07 20:07:20.689] DEBUG[29374] jitterbuf.c: head 14602, tail 14622, ts 6594 [2012-03-07 20:07:20.689] DEBUG[29374] jitterbuf.c: head 14602, tail 14642, ts 6614 [2012-03-07 20:07:20.689] DEBUG[29374] jitterbuf.c: head 14602, tail 14662, ts 6634 [2012-03-07 20:07:20.739] DEBUG[29378] jitterbuf.c: head 14662, tail 14682, ts 6654 [2012-03-07 20:07:20.739] DEBUG[29378] jitterbuf.c: head 14662, tail 14702, ts 6674 [2012-03-07 20:07:20.791] DEBUG[29381] jitterbuf.c: head 14702, tail 14722, ts 6694 [2012-03-07 20:07:20.791] DEBUG[29381] jitterbuf.c: head 14702, tail 14742, ts 6714 [2012-03-07 20:07:20.791] DEBUG[29381] jitterbuf.c: head 14702, tail 14762, ts 6734 [2012-03-07 20:07:20.839] DEBUG[29375] jitterbuf.c: head 14762, tail 14782, ts 6754 [2012-03-07 20:07:20.839] DEBUG[29375] jitterbuf.c: head 14762, tail 14802, ts 6774 [2012-03-07 20:07:20.891] DEBUG[29378] jitterbuf.c: head 14802, tail 14822, ts 6794 [2012-03-07 20:07:20.891] DEBUG[29378] jitterbuf.c: head 14802, tail 14842, ts 6814 [2012-03-07 20:07:20.891] DEBUG[29378] jitterbuf.c: head 14802, tail 14862, ts 6834 [2012-03-07 20:07:20.939] DEBUG[29382] jitterbuf.c: head 14862, tail 14882, ts 6854 [2012-03-07 20:07:20.939] DEBUG[29382] jitterbuf.c: head 14862, tail 14902, ts 6874 [2012-03-07 20:07:20.991] DEBUG[29375] jitterbuf.c: head 14902, tail 14922, ts 6894 [2012-03-07 20:07:20.991] DEBUG[29375] jitterbuf.c: head 14902, tail 14942, ts 6914 [2012-03-07 20:07:20.991] DEBUG[29375] jitterbuf.c: head 14902, tail 14962, ts 6934 [2012-03-07 20:07:21.039] DEBUG[29379] jitterbuf.c: head 14962, tail 14982, ts 6954 [2012-03-07 20:07:21.039] DEBUG[29379] jitterbuf.c: head 14962, tail 15002, ts 6974 [2012-03-07 20:07:21.091] DEBUG[29382] jitterbuf.c: head 15002, tail 15022, ts 6994 [2012-03-07 20:07:21.091] DEBUG[29382] jitterbuf.c: head 15002, tail 15042, ts 7014 [2012-03-07 20:07:21.091] DEBUG[29382] jitterbuf.c: head 15002, tail 15062, ts 7034 [2012-03-07 20:07:21.139] DEBUG[29376] jitterbuf.c: head 15062, tail 15082, ts 7054 [2012-03-07 20:07:21.139] DEBUG[29376] jitterbuf.c: head 15062, tail 15102, ts 7074 [2012-03-07 20:07:21.190] DEBUG[29379] jitterbuf.c: head 15102, tail 15122, ts 7094 [2012-03-07 20:07:21.190] DEBUG[29379] jitterbuf.c: head 15102, tail 15142, ts 7114 [2012-03-07 20:07:21.191] DEBUG[29379] jitterbuf.c: head 15102, tail 15162, ts 7134 [2012-03-07 20:07:21.238] DEBUG[29383] jitterbuf.c: head 15162, tail 15182, ts 7154 [2012-03-07 20:07:21.238] DEBUG[29383] jitterbuf.c: head 15162, tail 15202, ts 7174 [2012-03-07 20:07:21.288] DEBUG[29376] jitterbuf.c: head 15202, tail 15222, ts 7194 [2012-03-07 20:07:21.288] DEBUG[29376] jitterbuf.c: head 15202, tail 15242, ts 7214 [2012-03-07 20:07:21.288] DEBUG[29376] jitterbuf.c: head 15202, tail 15262, ts 7234 [2012-03-07 20:07:21.337] DEBUG[29380] jitterbuf.c: head 15262, tail 15282, ts 7254 [2012-03-07 20:07:21.337] DEBUG[29380] jitterbuf.c: head 15262, tail 15302, ts 7274 [2012-03-07 20:07:21.387] DEBUG[29383] jitterbuf.c: head 15302, tail 15322, ts 7294 [2012-03-07 20:07:21.387] DEBUG[29383] jitterbuf.c: head 15302, tail 15342, ts 7314 [2012-03-07 20:07:21.387] DEBUG[29383] jitterbuf.c: head 15302, tail 15362, ts 7334 [2012-03-07 20:07:21.437] DEBUG[29377] jitterbuf.c: head 15362, tail 15382, ts 7354 [2012-03-07 20:07:21.437] DEBUG[29377] jitterbuf.c: head 15362, tail 15402, ts 7374 [2012-03-07 20:07:21.488] DEBUG[29380] jitterbuf.c: head 15402, tail 15422, ts 7394 [2012-03-07 20:07:21.488] DEBUG[29380] jitterbuf.c: head 15402, tail 15442, ts 7414 [2012-03-07 20:07:21.488] DEBUG[29380] jitterbuf.c: head 15402, tail 15462, ts 7434 [2012-03-07 20:07:21.539] DEBUG[29374] jitterbuf.c: head 15462, tail 15482, ts 7454 [2012-03-07 20:07:21.539] DEBUG[29374] jitterbuf.c: head 15462, tail 15502, ts 7474 [2012-03-07 20:07:21.558] VERBOSE[29386] sig_pri.c: -- Span 2: Channel 0/31 got hangup request, cause 16 [2012-03-07 20:07:21.565] DEBUG[29403] channel.c: Didn't get a frame from channel: DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:21.565] DEBUG[29403] chan_iax2.c: Indicating condition 20 [2012-03-07 20:07:21.565] DEBUG[29403] channel.c: Bridge stops bridging channels IAX2/trunk-1707 and DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:21.565] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 011 ISeqno: 020 Type: CONTROL Subclass: SRCUPDT [2012-03-07 20:07:21.565] VERBOSE[29372] chan_iax2.c: Timestamp: 15503ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:21.565] DEBUG[29403] channel.c: Soft-Hanging up channel 'IAX2/trunk-1707' [2012-03-07 20:07:21.565] DEBUG[29403] pbx.c: Launching 'NoOp' [2012-03-07 20:07:21.565] VERBOSE[29403] pbx.c: -- Executing [h@dialpbx:1] NoOp("IAX2/trunk-1707", "--- Hangup for 5xxxx7 in dialpbx context ---") in new stack [2012-03-07 20:07:21.565] DEBUG[29403] pbx.c: Expression result is '1' [2012-03-07 20:07:21.565] DEBUG[29403] pbx.c: Launching 'GotoIf' [2012-03-07 20:07:21.565] VERBOSE[29403] pbx.c: -- Executing [h@dialpbx:2] GotoIf("IAX2/trunk-1707", "1?h-email,1") in new stack [2012-03-07 20:07:21.565] VERBOSE[29403] pbx.c: -- Goto (dialpbx,h-email,1) [2012-03-07 20:07:21.565] DEBUG[29403] pbx.c: Launching 'NoOp' [2012-03-07 20:07:21.565] VERBOSE[29403] pbx.c: -- Executing [h-email@dialpbx:1] NoOp("IAX2/trunk-1707", "--- Processing email notification ---") in new stack [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Expression result is '0' [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Launching 'GotoIf' [2012-03-07 20:07:21.566] VERBOSE[29403] pbx.c: -- Executing [h-email@dialpbx:2] GotoIf("IAX2/trunk-1707", "0?done") in new stack [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Not taking any branch [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Expression result is '0' [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Launching 'ExecIf' [2012-03-07 20:07:21.566] VERBOSE[29403] pbx.c: -- Executing [h-email@dialpbx:3] ExecIf("IAX2/trunk-1707", "0?Set(EmailMessage=You have missed a call from "" <901xxxxxxxx5>)") in new stack [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Expression result is '0' [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Launching 'ExecIf' [2012-03-07 20:07:21.566] VERBOSE[29403] pbx.c: -- Executing [h-email@dialpbx:4] ExecIf("IAX2/trunk-1707", "0?Set(EmailMessage="" <901xxxxxxxx5> hung up VM)") in new stack [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Expression result is '0' [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Launching 'ExecIf' [2012-03-07 20:07:21.566] VERBOSE[29403] pbx.c: -- Executing [h-email@dialpbx:5] ExecIf("IAX2/trunk-1707", "0?Set(EmailMessage="" <901xxxxxxxx5> hung up VM)") in new stack [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Launching 'GotoIf' [2012-03-07 20:07:21.566] VERBOSE[29403] pbx.c: -- Executing [h-email@dialpbx:6] GotoIf("IAX2/trunk-1707", "1?done") in new stack [2012-03-07 20:07:21.566] VERBOSE[29403] pbx.c: -- Goto (dialpbx,h-email,8) [2012-03-07 20:07:21.566] DEBUG[29403] pbx.c: Launching 'Return' [2012-03-07 20:07:21.566] VERBOSE[29403] pbx.c: -- Executing [h-email@dialpbx:8] Return("IAX2/trunk-1707", "") in new stack [2012-03-07 20:07:21.566] DEBUG[29403] channel.c: Hanging up channel 'DAHDI/i2/5xxxx7-1' [2012-03-07 20:07:21.566] DEBUG[29403] chan_dahdi.c: dahdi_hangup(DAHDI/i2/5xxxx7-1) [2012-03-07 20:07:21.566] DEBUG[29403] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:21.567] DEBUG[29403] sig_pri.c: sig_pri_hangup 62 [2012-03-07 20:07:21.567] DEBUG[29403] sig_pri.c: Not yet hungup... Calling hangup with cause 16, and clearing call [2012-03-07 20:07:21.572] DEBUG[29403] chan_dahdi.c: Disabled echo cancellation on channel 62 [2012-03-07 20:07:21.572] DEBUG[29403] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:21.572] DEBUG[29403] chan_dahdi.c: Updated conferencing on 62, with 0 conference users [2012-03-07 20:07:21.572] DEBUG[29403] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/i2/5xxxx7-1 [2012-03-07 20:07:21.572] VERBOSE[29403] chan_dahdi.c: -- Hungup 'DAHDI/i2/5xxxx7-1' [2012-03-07 20:07:21.572] DEBUG[29403] app_dial.c: Exiting with DIALSTATUS=ANSWER. [2012-03-07 20:07:21.572] DEBUG[29403] pbx.c: Spawn extension (dialpbx,s,6) exited non-zero on 'IAX2/trunk-1707' [2012-03-07 20:07:21.572] VERBOSE[29403] pbx.c: == Spawn extension (dialpbx, s, 6) exited non-zero on 'IAX2/trunk-1707' [2012-03-07 20:07:21.572] DEBUG[29403] channel.c: Soft-Hanging up channel 'IAX2/trunk-1707' [2012-03-07 20:07:21.572] DEBUG[29403] channel.c: Hanging up channel 'IAX2/trunk-1707' [2012-03-07 20:07:21.572] DEBUG[29403] chan_iax2.c: We're hanging up IAX2/trunk-1707 now... [2012-03-07 20:07:21.572] VERBOSE[29403] chan_iax2.c: -- Hungup 'IAX2/trunk-1707' [2012-03-07 20:07:21.572] VERBOSE[29372] chan_iax2.c: Tx-Frame Retry[000] -- OSeqno: 012 ISeqno: 020 Type: IAX Subclass: HANGUP [2012-03-07 20:07:21.572] VERBOSE[29372] chan_iax2.c: Timestamp: 15527ms SCall: 01707 DCall: 17440 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:21.572] VERBOSE[29372] chan_iax2.c: CAUSE CODE : 16 [2012-03-07 20:07:21.572] VERBOSE[29372] chan_iax2.c: [2012-03-07 20:07:21.591] DEBUG[29376] jitterbuf.c: head 15482, tail 15522, ts 7494 [2012-03-07 20:07:21.591] DEBUG[29376] jitterbuf.c: head 15482, tail 15542, ts 7514 [2012-03-07 20:07:21.591] DEBUG[29376] jitterbuf.c: head 15482, tail 15562, ts 7534 [2012-03-07 20:07:21.592] VERBOSE[29377] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 020 ISeqno: 012 Type: IAX Subclass: ACK [2012-03-07 20:07:21.592] VERBOSE[29377] chan_iax2.c: Timestamp: 15503ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:21.592] DEBUG[29377] chan_iax2.c: Received packet 20, (6, 4) [2012-03-07 20:07:21.592] DEBUG[29377] chan_iax2.c: Cancelling transmission of packet 11 [2012-03-07 20:07:21.592] DEBUG[29377] chan_iax2.c: IAX subclass 4 received [2012-03-07 20:07:21.593] VERBOSE[29378] chan_iax2.c: Rx-Frame Retry[ No] -- OSeqno: 020 ISeqno: 013 Type: IAX Subclass: ACK [2012-03-07 20:07:21.593] VERBOSE[29378] chan_iax2.c: Timestamp: 15527ms SCall: 17440 DCall: 01707 [10.xxx.xxx.xx2:4569] [2012-03-07 20:07:21.593] DEBUG[29378] chan_iax2.c: Received packet 20, (6, 4) [2012-03-07 20:07:21.593] DEBUG[29378] chan_iax2.c: Cancelling transmission of packet 12 [2012-03-07 20:07:21.594] DEBUG[29378] chan_iax2.c: Really destroying 1707, having been acked on final message [2012-03-07 20:07:21.594] DEBUG[29378] chan_iax2.c: schedule decrement of callno used for 10.xxx.xxx.xx2 in 60 seconds