[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: <none>  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: <none>  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 <d13d477a-3765-433c-8f13-7f57369fdd64>
[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 <d13d477a-3765-433c-8f13-7f57369fdd64>
[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