[Nov 28 09:43:25] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5440 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:25] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5460 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:25] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5480 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:25] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5500 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:25] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5520 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:25] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5540 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:25] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5560 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5580 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5600 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5620 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5640 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: VOICE Subclass: 4 [Nov 28 09:43:26] Timestamp: 05640ms SCall: 13234 DCall: 12944 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:10404 socket_process_helper: Received packet 1, (2, 0) [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:10635 socket_process_helper: Ooh, voice format changed to 'ulaw' [Nov 28 09:43:26] DEBUG[18886][C-00000354]: channel_internal_api.c:682 ast_channel_nativeformats_set: IAX2/ASTERISK-SIDE-B-IP:4569-12944: Formats: (ulaw) [Nov 28 09:43:26] DEBUG[18886][C-00000354]: channel_internal_api.c:700 ast_channel_nativeformats_set: New topology set [Nov 28 09:43:26] DEBUG[18886][C-00000354]: channel.c:5752 set_format: Channel IAX2/ASTERISK-SIDE-B-IP:4569-12944 setting read format path: ulaw -> ulaw [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5640 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK [Nov 28 09:43:26] Timestamp: 05640ms SCall: 12944 DCall: 13234 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5640 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6165 calc_timestamp: predicted timestamp skew (5685) > max (160), using real ts instead. [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7680 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5660 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5660 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7700 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5680 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5680 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7720 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5700 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5700 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7740 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5720 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5720 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7760 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5740 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5740 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7780 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5760 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5760 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7800 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5780 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5780 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7820 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5800 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5800 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7840 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5820 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5820 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7860 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5840 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5840 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7880 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5860 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5860 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7900 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5880 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5880 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7920 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5900 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5900 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7940 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5920 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5920 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7960 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5940 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5940 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7980 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5960 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5960 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8000 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 5980 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=5980 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8020 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6000 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6000 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8040 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6020 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6020 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8060 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6040 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6040 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8080 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6060 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6060 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8100 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6080 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6080 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8120 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6100 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6100 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8140 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6120 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6120 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8160 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6140 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6140 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8180 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6160 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6160 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8200 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6180 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6180 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8220 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6200 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6200 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8240 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6220 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6220 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8260 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6240 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6240 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8280 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6260 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6260 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8300 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6280 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6280 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8320 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6300 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6300 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8340 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6320 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6320 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8360 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6340 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6340 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8380 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6360 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6360 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8400 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6380 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6380 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8420 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6400 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6400 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8440 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6420 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6420 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8460 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6440 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6440 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8480 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6460 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6460 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8500 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6480 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6480 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8520 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6500 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6500 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8540 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6520 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6520 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8560 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6540 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:26] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6540 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8580 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:26] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6560 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6560 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8600 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6580 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6580 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8620 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6600 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6600 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8640 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6620 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6620 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8660 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6640 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6640 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8680 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6660 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6660 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8700 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6680 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6680 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8720 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6700 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6700 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8740 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6720 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6720 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8760 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6740 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6740 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8780 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6760 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6760 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8800 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6780 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6780 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8820 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6800 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6800 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8840 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6820 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6820 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8860 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6840 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6840 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8880 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6860 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6860 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8900 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6880 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6880 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8920 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6900 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6900 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8940 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6920 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6920 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8960 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6940 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6940 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8980 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6960 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6960 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9000 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 6980 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:9195 __sip_alloc: Allocating new SIP dialog for 2b95e91b392c39872e0e071219f1eea3@127.0.1.1:16262 - OPTIONS (No RTP) [Nov 28 09:43:27] DEBUG[18878]: acl.c:1047 ast_ouraddrfor: For destination '10.55.1.196', our source address is '10.55.1.247'. [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:4036 ast_sip_ouraddrfor: Setting AST_TRANSPORT_UDP with address 10.55.1.247:16262 [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:8984 change_callid_pvt: SIP call-id changed from '2b95e91b392c39872e0e071219f1eea3@127.0.1.1:16262' to '56c938d54eb553a14dd17fa622456fba@10.55.1.247:16262' [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:3503 initialize_initreq: Initializing initreq for method OPTIONS - callid 56c938d54eb553a14dd17fa622456fba@10.55.1.247:16262 [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:3860 __sip_xmit: Trying to put 'OPTIONS sip' onto UDP socket destined for 10.55.1.196:5060 [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:9602 __find_call: = Looking for Call ID: 56c938d54eb553a14dd17fa622456fba@10.55.1.247:16262 (Checking To) --From tag as5bd2dbec --To-tag 1389092921 [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:4663 __sip_ack: Stopping retransmission on '56c938d54eb553a14dd17fa622456fba@10.55.1.247:16262' of Request 102: Match Found [Nov 28 09:43:27] DEBUG[18878]: chan_sip.c:6735 sip_pvt_dtor: Destroying SIP dialog 56c938d54eb553a14dd17fa622456fba@10.55.1.247:16262 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=6980 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9020 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7000 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7000 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9040 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7020 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7020 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9060 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7040 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7040 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9080 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7060 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7060 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9100 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7080 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7080 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9120 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7100 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7100 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9140 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7120 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7120 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9160 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7140 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7140 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9180 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7160 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7160 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9200 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7180 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7180 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9220 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7200 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7200 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9240 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7220 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7220 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9260 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7240 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7240 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9280 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7260 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7260 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9300 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7280 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7280 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9320 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7300 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7300 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9340 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7320 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18884][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7320 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9360 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7340 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18886][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7340 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9380 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7360 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18889][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7360 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9400 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7380 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18891][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7380 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9420 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7400 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7400 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:6509 encrypt_frame: Encoding mini frame with length 164 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 9440 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7420 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7440 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7460 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7480 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7500 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7520 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7540 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:27] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7560 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7580 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7600 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7620 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7640 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7660 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7680 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7700 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7720 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7740 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7760 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7780 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: ANSWER [Nov 28 09:43:28] Timestamp: 07774ms SCall: 13234 DCall: 12944 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[18882][C-00000354]: chan_iax2.c:10404 socket_process_helper: Received packet 2, (4, 4) [Nov 28 09:43:28] DEBUG[18882][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7774 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 07774ms SCall: 12944 DCall: 13234 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7774 [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: (255?) [Nov 28 09:43:28] Timestamp: 07777ms SCall: 13234 DCall: 12944 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[18882][C-00000354]: chan_iax2.c:10404 socket_process_helper: Received packet 3, (4, -1) [Nov 28 09:43:28] DEBUG[18882][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7777 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 07777ms SCall: 12944 DCall: 13234 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[18882][C-00000354]: chan_iax2.c:11996 socket_process_helper: For call=12944, set last=7777 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: app_dial.c:1401 wait_for_answer: IAX2/ASTERISK-SIDE-B-IP:4569-12944 answered IAX2/sfcu-11602 [Nov 28 09:43:28] -- IAX2/ASTERISK-SIDE-B-IP:4569-12944 answered IAX2/sfcu-11602 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: app_dial.c:1883 wait_for_answer: IAX2/sfcu-11602: Answered by IAX2/ASTERISK-SIDE-B-IP:4569-12944 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/ASTERISK-SIDE-B-IP:4569-12944 setting read format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/sfcu-11602 setting write format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/sfcu-11602 setting read format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/ASTERISK-SIDE-B-IP:4569-12944 setting write format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:5732 iax2_answer: Answering IAX2 call [Nov 28 09:43:28] TE-Frame Retry[000] -- OSeqno: 005 ISeqno: 009 Type: CONTROL Subclass: ANSWER [Nov 28 09:43:28] Timestamp: 09815ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:6493 encrypt_frame: Encoding full frame 4/4 with length 12 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:6501 encrypt_frame: Encoding full frame 4/4 with length 12 + 24 padding (15=88) [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:5743 iax2_indicate: Indicating condition -1 [Nov 28 09:43:28] TE-Frame Retry[000] -- OSeqno: 006 ISeqno: 009 Type: CONTROL Subclass: (255?) [Nov 28 09:43:28] Timestamp: 09818ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:6493 encrypt_frame: Encoding full frame 4/255 with length 12 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:6501 encrypt_frame: Encoding full frame 4/255 with length 12 + 24 padding (15=08) [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis.c:579 stasis_topic_create_with_detail: Creating topic. name: bridge:all/bridge:d13d477a-3765-433c-8f13-7f57369fdd64, detail: [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis.c:613 stasis_topic_create_with_detail: Topic 'bridge:all/bridge:d13d477a-3765-433c-8f13-7f57369fdd64': 0x7fa8d4134bc0 created [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge_native_rtp.c:774 native_rtp_bridge_compatible: Bridge 'd13d477a-3765-433c-8f13-7f57369fdd64' can not use native RTP bridge as two channels are required [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:535 find_best_technology: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:525 find_best_technology: Bridge technology holding_bridge does not have any capabilities we want. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:530 find_best_technology: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:549 find_best_technology: Chose bridge technology simple_bridge [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:802 bridge_base_init: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: calling simple_bridge technology constructor [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:810 bridge_base_init: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: calling simple_bridge technology start [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis_bridges.c:290 bridge_snapshot_update_create: Update: 0x7fa8d4115118 Old: New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis_bridges.c:270 bridge_snapshot_update_dtor: Update: 0x7fa8d4115118 Old: New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[18862]: devicestate.c:361 _ast_device_state: No provider found, checking channel drivers for IAX2 - ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[18862]: chan_iax2.c:14556 iax2_devicestate: Checking device state for device ASTERISK-SIDE-B-IP [Nov 28 09:43:28] DEBUG[18862]: devicestate.c:466 do_state_change: Changing state for IAX2/ASTERISK-SIDE-B-IP:4569 - state 4 (Invalid) [Nov 28 09:43:28] DEBUG[18862]: devicestate.c:361 _ast_device_state: No provider found, checking channel drivers for IAX2 - sfcu [Nov 28 09:43:28] DEBUG[18862]: chan_iax2.c:14556 iax2_devicestate: Checking device state for device sfcu [Nov 28 09:43:28] DEBUG[18862]: chan_iax2.c:14565 iax2_devicestate: Found peer. What's device state of sfcu? addr=ASTERISK-SIDE-A-IP:4569, defaddr=(null) maxms=0, lastms=0 [Nov 28 09:43:28] DEBUG[18862]: devicestate.c:466 do_state_change: Changing state for IAX2/sfcu - state 2 (In use) [Nov 28 09:43:28] DEBUG[18879]: chan_iax2.c:3414 send_packet: Sending 9815 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[000] -- OSeqno: 058 ISeqno: 209 Type: (198?) Subclass: 24 [Nov 28 09:43:28] Timestamp: 3843520004ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18879]: chan_iax2.c:3414 send_packet: Sending 9818 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[000] -- OSeqno: 114 ISeqno: 110 Type: (60?) Subclass: 140 [Nov 28 09:43:28] Timestamp: 312680726ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18901]: app_queue.c:2589 device_state_cb: Device 'IAX2/sfcu' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 28 09:43:28] DEBUG[18901]: app_queue.c:2589 device_state_cb: Device 'IAX2/ASTERISK-SIDE-B-IP:4569' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge_channel.c:2911 bridge_channel_internal_join: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: 0x7fa8d407dba0(IAX2/ASTERISK-SIDE-B-IP:4569-12944) is joining [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge_channel.c:2251 bridge_channel_internal_push_full: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: pushing 0x7fa8d407dba0(IAX2/ASTERISK-SIDE-B-IP:4569-12944) [Nov 28 09:43:28] -- Channel IAX2/ASTERISK-SIDE-B-IP:4569-12944 joined 'simple_bridge' basic-bridge [Nov 28 09:43:28] DEBUG[76886][C-00000354]: stasis_bridges.c:290 bridge_snapshot_update_create: Update: 0x7fa8b401db98 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: stasis_bridges.c:270 bridge_snapshot_update_dtor: Update: 0x7fa8b401db98 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge_native_rtp.c:774 native_rtp_bridge_compatible: Bridge 'd13d477a-3765-433c-8f13-7f57369fdd64' can not use native RTP bridge as two channels are required [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge.c:535 find_best_technology: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge.c:525 find_best_technology: Bridge technology holding_bridge does not have any capabilities we want. [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge.c:525 find_best_technology: Bridge technology softmix does not have any capabilities we want. [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge.c:549 find_best_technology: Chose bridge technology simple_bridge [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge.c:1058 smart_bridge_operation: Bridge d13d477a-3765-433c-8f13-7f57369fdd64 is already using the new technology. [Nov 28 09:43:28] DEBUG[76886][C-00000354]: bridge.c:448 bridge_channel_complete_join: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: 0x7fa8d407dba0(IAX2/ASTERISK-SIDE-B-IP:4569-12944) is joining simple_bridge technology [Nov 28 09:43:28] DEBUG[76886][C-00000354]: stasis_bridges.c:290 bridge_snapshot_update_create: Update: 0x7fa8b4019e88 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: stasis_bridges.c:270 bridge_snapshot_update_dtor: Update: 0x7fa8b4019e88 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge_channel.c:2911 bridge_channel_internal_join: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: 0x7fa8d411a650(IAX2/sfcu-11602) is joining [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge_channel.c:2251 bridge_channel_internal_push_full: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: pushing 0x7fa8d411a650(IAX2/sfcu-11602) [Nov 28 09:43:28] -- Channel IAX2/sfcu-11602 joined 'simple_bridge' basic-bridge [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis_bridges.c:290 bridge_snapshot_update_create: Update: 0x7fa8d405bf88 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis_bridges.c:270 bridge_snapshot_update_dtor: Update: 0x7fa8d405bf88 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge_native_rtp.c:647 native_rtp_bridge_compatible_check: Bridge 'd13d477a-3765-433c-8f13-7f57369fdd64'. Checking compatability for channels 'IAX2/ASTERISK-SIDE-B-IP:4569-12944' and 'IAX2/sfcu-11602' [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge_native_rtp.c:657 native_rtp_bridge_compatible_check: Bridge 'd13d477a-3765-433c-8f13-7f57369fdd64' can not use native RTP bridge as channel 'IAX2/sfcu-11602' has features which prevent it [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:535 find_best_technology: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:525 find_best_technology: Bridge technology holding_bridge does not have any capabilities we want. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:525 find_best_technology: Bridge technology softmix does not have any capabilities we want. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:549 find_best_technology: Chose bridge technology simple_bridge [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:1058 smart_bridge_operation: Bridge d13d477a-3765-433c-8f13-7f57369fdd64 is already using the new technology. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: bridge.c:448 bridge_channel_complete_join: Bridge d13d477a-3765-433c-8f13-7f57369fdd64: 0x7fa8d411a650(IAX2/sfcu-11602) is joining simple_bridge technology [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/sfcu-11602 setting read format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/ASTERISK-SIDE-B-IP:4569-12944 setting write format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/ASTERISK-SIDE-B-IP:4569-12944 setting read format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: channel.c:5752 set_format: Channel IAX2/sfcu-11602 setting write format path: ulaw -> ulaw [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis_bridges.c:290 bridge_snapshot_update_create: Update: 0x7fa8d4024cb8 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: stasis_bridges.c:270 bridge_snapshot_update_dtor: Update: 0x7fa8d4024cb8 Old: d13d477a-3765-433c-8f13-7f57369fdd64 New: d13d477a-3765-433c-8f13-7f57369fdd64 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:5743 iax2_indicate: Indicating condition 26 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:7745 send_command: Callno 11602: Blocked sending control frame 26. [Nov 28 09:43:28] DEBUG[18870]: cdr.c:1470 cdr_object_finalize: Finalized CDR for IAX2/ASTERISK-SIDE-B-IP:4569-12944 - start 1638110600.454928 answer 1638110608.228645 end 1638110608.232358 dur 7.777 bill 0.003 dispo ANSWERED [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:5743 iax2_indicate: Indicating condition 26 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:7745 send_command: Callno 12944: Blocked sending control frame 26. [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:5743 iax2_indicate: Indicating condition -1 [Nov 28 09:43:28] TE-Frame Retry[000] -- OSeqno: 007 ISeqno: 009 Type: CONTROL Subclass: (255?) [Nov 28 09:43:28] Timestamp: 09821ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:6493 encrypt_frame: Encoding full frame 4/255 with length 12 [Nov 28 09:43:28] DEBUG[76877][C-00000354]: chan_iax2.c:6501 encrypt_frame: Encoding full frame 4/255 with length 12 + 24 padding (15=88) [Nov 28 09:43:28] DEBUG[18879]: chan_iax2.c:3414 send_packet: Sending 9821 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[000] -- OSeqno: 066 ISeqno: 255 Type: (176?) Subclass: 93 [Nov 28 09:43:28] Timestamp: 3765014782ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7800 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 063 ISeqno: 180 Type: (176?) Subclass: 152 [Nov 28 09:43:28] Timestamp: 1098052146ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:6448 decode_frame: Decoding full frame with length 36 (padding = 24) (15=e8) [Nov 28 09:43:28] RD-Frame Retry[ No] -- OSeqno: 009 ISeqno: 006 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 09815ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:10404 socket_process_helper: Received packet 9, (6, 4) [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:10501 socket_process_helper: Cancelling transmission of packet 5 [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:10675 socket_process_helper: IAX subclass 4 received [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 246 ISeqno: 097 Type: (94?) Subclass: 77 [Nov 28 09:43:28] Timestamp: 2676088567ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:6448 decode_frame: Decoding full frame with length 36 (padding = 24) (15=d8) [Nov 28 09:43:28] RD-Frame Retry[ No] -- OSeqno: 009 ISeqno: 007 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 09818ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:10404 socket_process_helper: Received packet 9, (6, 4) [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:10501 socket_process_helper: Cancelling transmission of packet 6 [Nov 28 09:43:28] DEBUG[18888]: chan_iax2.c:10675 socket_process_helper: IAX subclass 4 received [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 124 ISeqno: 225 Type: (112?) Subclass: 202 [Nov 28 09:43:28] Timestamp: 3621848759ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18890]: chan_iax2.c:6448 decode_frame: Decoding full frame with length 36 (padding = 24) (15=d8) [Nov 28 09:43:28] RD-Frame Retry[ No] -- OSeqno: 009 ISeqno: 008 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 09821ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18890]: chan_iax2.c:10404 socket_process_helper: Received packet 9, (6, 4) [Nov 28 09:43:28] DEBUG[18890]: chan_iax2.c:10501 socket_process_helper: Cancelling transmission of packet 7 [Nov 28 09:43:28] DEBUG[18890]: chan_iax2.c:10675 socket_process_helper: IAX subclass 4 received [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 220 ISeqno: 085 Type: (248?) Subclass: 187 [Nov 28 09:43:28] Timestamp: 2808779475ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:6448 decode_frame: Decoding full frame with length 36 (padding = 24) (15=58) [Nov 28 09:43:28] RD-Frame Retry[ No] -- OSeqno: 009 ISeqno: 008 Type: CONTROL Subclass: (255?) [Nov 28 09:43:28] Timestamp: 09563ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:10404 socket_process_helper: Received packet 9, (4, -1) [Nov 28 09:43:28] TE-Frame Retry[-01] -- OSeqno: 008 ISeqno: 010 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 09563ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:6493 encrypt_frame: Encoding full frame 6/4 with length 12 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:6501 encrypt_frame: Encoding full frame 6/4 with length 12 + 24 padding (15=58) [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:3414 send_packet: Sending 9563 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[-01] -- OSeqno: 231 ISeqno: 049 Type: (122?) Subclass: 189 [Nov 28 09:43:28] Timestamp: 3899662916ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:11981 socket_process_helper: Received out of order packet... (type=4, subclass -1, ts = 9563, last = 9596) [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:5743 iax2_indicate: Indicating condition -1 [Nov 28 09:43:28] DEBUG[18879]: chan_iax2.c:3414 send_packet: Sending 7803 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 004 Type: CONTROL Subclass: (255?) [Nov 28 09:43:28] Timestamp: 07803ms SCall: 12944 DCall: 13234 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 003 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 07803ms SCall: 13234 DCall: 12944 ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[18887][C-00000354]: chan_iax2.c:10404 socket_process_helper: Received packet 4, (6, 4) [Nov 28 09:43:28] DEBUG[18887][C-00000354]: chan_iax2.c:10501 socket_process_helper: Cancelling transmission of packet 2 [Nov 28 09:43:28] DEBUG[18887][C-00000354]: chan_iax2.c:10675 socket_process_helper: IAX subclass 4 received [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7820 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7840 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7860 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7880 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7900 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7920 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7940 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7960 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 013 ISeqno: 106 Type: (27?) Subclass: 199 [Nov 28 09:43:28] Timestamp: 2136753089ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:6448 decode_frame: Decoding full frame with length 36 (padding = 24) (15=58) [Nov 28 09:43:28] RD-Frame Retry[ No] -- OSeqno: 010 ISeqno: 008 Type: IAX Subclass: LAGRQ [Nov 28 09:43:28] Timestamp: 09761ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:10404 socket_process_helper: Received packet 10, (6, 11) [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:10675 socket_process_helper: IAX subclass 11 received [Nov 28 09:43:28] TE-Frame Retry[000] -- OSeqno: 008 ISeqno: 011 Type: IAX Subclass: LAGRP [Nov 28 09:43:28] Timestamp: 09761ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:6493 encrypt_frame: Encoding full frame 6/12 with length 12 [Nov 28 09:43:28] DEBUG[18889]: chan_iax2.c:6501 encrypt_frame: Encoding full frame 6/12 with length 12 + 24 padding (15=58) [Nov 28 09:43:28] DEBUG[18879]: chan_iax2.c:3414 send_packet: Sending 9761 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[000] -- OSeqno: 116 ISeqno: 009 Type: (152?) Subclass: 90 [Nov 28 09:43:28] Timestamp: 3468772390ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 7980 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 014 ISeqno: 072 Type: (139?) Subclass: 164 [Nov 28 09:43:28] Timestamp: 3452981762ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18891]: chan_iax2.c:6448 decode_frame: Decoding full frame with length 36 (padding = 24) (15=58) [Nov 28 09:43:28] RD-Frame Retry[ No] -- OSeqno: 011 ISeqno: 009 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 09761ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18891]: chan_iax2.c:10404 socket_process_helper: Received packet 11, (6, 4) [Nov 28 09:43:28] DEBUG[18891]: chan_iax2.c:10501 socket_process_helper: Cancelling transmission of packet 8 [Nov 28 09:43:28] DEBUG[18891]: chan_iax2.c:10675 socket_process_helper: IAX subclass 4 received [Nov 28 09:43:28] TE-Frame Retry[000] -- OSeqno: 009 ISeqno: 011 Type: IAX Subclass: LAGRQ [Nov 28 09:43:28] Timestamp: 10035ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18883]: chan_iax2.c:6493 encrypt_frame: Encoding full frame 6/11 with length 12 [Nov 28 09:43:28] DEBUG[18883]: chan_iax2.c:6501 encrypt_frame: Encoding full frame 6/11 with length 12 + 24 padding (15=58) [Nov 28 09:43:28] DEBUG[18879]: chan_iax2.c:3414 send_packet: Sending 10035 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[000] -- OSeqno: 184 ISeqno: 041 Type: (16?) Subclass: 65 [Nov 28 09:43:28] Timestamp: 1383719262ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8000 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] Rx-Frame Retry[ No] -- OSeqno: 143 ISeqno: 055 Type: (170?) Subclass: 107 [Nov 28 09:43:28] Timestamp: 1988547354ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:6448 decode_frame: Decoding full frame with length 36 (padding = 24) (15=58) [Nov 28 09:43:28] RD-Frame Retry[ No] -- OSeqno: 011 ISeqno: 010 Type: IAX Subclass: LAGRP [Nov 28 09:43:28] Timestamp: 10035ms SCall: 17130 DCall: 11602 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:10404 socket_process_helper: Received packet 11, (6, 12) [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:10501 socket_process_helper: Cancelling transmission of packet 9 [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:10675 socket_process_helper: IAX subclass 12 received [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:11224 socket_process_helper: Peer ASTERISK-SIDE-A-IP:4569 lag measured as 10ms [Nov 28 09:43:28] TE-Frame Retry[-01] -- OSeqno: 010 ISeqno: 012 Type: IAX Subclass: ACK [Nov 28 09:43:28] Timestamp: 10035ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:6493 encrypt_frame: Encoding full frame 6/4 with length 12 [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:6501 encrypt_frame: Encoding full frame 6/4 with length 12 + 24 padding (15=58) [Nov 28 09:43:28] DEBUG[18885]: chan_iax2.c:3414 send_packet: Sending 10035 on 11602/17130 to ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] Tx-Frame Retry[-01] -- OSeqno: 025 ISeqno: 205 Type: (187?) Subclass: 47 [Nov 28 09:43:28] Timestamp: 2495981669ms SCall: 11602 DCall: 17130 ASTERISK-SIDE-A-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8020 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8040 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8060 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8080 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8100 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8120 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8140 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8160 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8180 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8200 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8220 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8240 on 12944/13234 to ASTERISK-SIDE-B-IP:4569 [Nov 28 09:43:28] DEBUG[76886][C-00000354]: chan_iax2.c:3414 send_packet: Sending 8260 on 12944/13234 to ASTERISK-SIDE-B-IP:4569