[Jan 22 18:10:25] Asterisk 13.7.0 built by root @ debian1 on a i686 running Linux on 2016-01-21 14:51:32 UTC [Jan 22 18:10:25] DEBUG[3251] config.c: Parsing /etc/asterisk/logger.conf [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=1 (rdata0x9dc3f64) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP request (873 bytes) from UDP:192.168.1.13:5060 ---> INVITE sip:123@192.168.1.24 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.13:5060;branch=z9hG4bK611163411562530295;rport From: ALICE ;tag=672914266 To: "123" Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 1 INVITE Contact: Max-Forwards: 70 Supported: replaces, join, path User-Agent: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 339 v=0 o=BOB 26803467 17124300 IN IP4 192.168.1.13 s=A conversation c=IN IP4 192.168.1.13 t=0 0 m=audio 10018 RTP/AVP 0 8 18 4 2 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:4 G723/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:9 G722/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Request msg INVITE/cseq=1 (rdata0x9d1a91c) [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.1.13:5060 does not match identify 'LocalTrunk' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_ip.c: '192.168.1.13:5060' did not match any identify section rules [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_user.c: Retrieved endpoint ALICE [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint .Response msg 401/INVITE/cseq=1 (tdta0xa05f200) created [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.24' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.24' and port ''. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP response (515 bytes) to UDP:192.168.1.13:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.1.13:5060;rport=5060;received=192.168.1.13;branch=z9hG4bK611163411562530295 Call-ID: 139211552013260-127592499222417@192.168.1.13 From: "ALICE" ;tag=672914266 To: "123" ;tag=z9hG4bK611163411562530295 CSeq: 1 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1453507830/809a83164d9dffcc7a7f86cc19d66070",opaque="5b08c4672aacb1ab",algorithm=md5,qop="auth" Server: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:30] DEBUG[3198] pjsip: tdta0xa05f200 .Destroying txdata Response msg 401/INVITE/cseq=1 (tdta0xa05f200) [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Request msg ACK/cseq=1 (rdata0x9dc3f64) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP request (334 bytes) from UDP:192.168.1.13:5060 ---> ACK sip:123@192.168.1.24 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.13:5060;branch=z9hG4bK611163411562530295;rport From: "ALICE" ;tag=672914266 To: "123" ;tag=z9hG4bK611163411562530295 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 1 ACK Max-Forwards: 70 Content-Length: 0 [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Request msg ACK/cseq=1 (rdata0x9d1a91c) [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.1.13:5060 does not match identify 'LocalTrunk' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_ip.c: '192.168.1.13:5060' did not match any identify section rules [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_user.c: Retrieved endpoint ALICE [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=2 (rdata0x9d1a91c) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP request (1142 bytes) from UDP:192.168.1.13:5060 ---> INVITE sip:123@192.168.1.24 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.13:5060;branch=z9hG4bK2526050211618511208;rport From: ALICE ;tag=672914266 To: "123" Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 2 INVITE Contact: Authorization: Digest username="ALICE", realm="asterisk", nonce="1453507830/809a83164d9dffcc7a7f86cc19d66070", uri="sip:123@192.168.1.24", response="08f6ed327df560c1b27c4f2397b456f9", algorithm=MD5, cnonce="ebcb82d2", opaque="5b08c4672aacb1ab", qop=auth, nc=00000001 Max-Forwards: 70 Supported: replaces, join, path User-Agent: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 339 v=0 o=BOB 26803467 17124300 IN IP4 192.168.1.13 s=A conversation c=IN IP4 192.168.1.13 t=0 0 m=audio 10018 RTP/AVP 0 8 18 4 2 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:4 G723/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:9 G722/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Request msg INVITE/cseq=2 (rdata0x9dc3f64) [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_ip.c: Source address 192.168.1.13:5060 does not match identify 'LocalTrunk' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_ip.c: '192.168.1.13:5060' did not match any identify section rules [Jan 22 18:10:30] DEBUG[3198] res_pjsip_endpoint_identifier_user.c: Retrieved endpoint ALICE [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint .Response msg 401/INVITE/cseq=2 (tdta0xa05f200) created [Jan 22 18:10:30] DEBUG[3198] res_pjsip_authenticator_digest.c: Calculated nonce 1453507830/809a83164d9dffcc7a7f86cc19d66070. Actual nonce is 1453507830/809a83164d9dffcc7a7f86cc19d66070 [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.24' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.24' and port ''. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3198] pjsip: tdta0xa05f200 .Destroying txdata Response msg 401/INVITE/cseq=2 (tdta0xa05f200) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ..Transaction created for Request msg INVITE/cseq=2 (rdata0x9dc3f64) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 .Incoming Request msg INVITE/cseq=2 (rdata0x9dc3f64) in state Null [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ..State changed from Null to Trying, event=RX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ...Transaction tsx0x9c2c0a4 state changed to Trying [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c .UAS dialog created [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c .Module mod-invite added as dialog usage, data=0x9f0dc14 [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ..Session count inc to 2 by mod-invite [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c .UAS invite session created for dialog dlg0x9ee697c [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c .Module Session Module added as dialog usage, data=(nil) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ..Session count inc to 2 by Session Module [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Negotiating incoming SDP media stream 'audio' using audio SDP handler [Jan 22 18:10:30] DEBUG[3199] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3199] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x9ee48ac' [Jan 22 18:10:30] DEBUG[3199] res_rtp_asterisk.c: Allocated port 15888 for RTP instance '0x9ee48ac' [Jan 22 18:10:30] DEBUG[3199] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:15888 (15888) for RTP instance '0x9ee48ac' [Jan 22 18:10:30] DEBUG[3199] pjsip: icess0x9f4247c ICE session created, comp_cnt=2, role is Unknown agent [Jan 22 18:10:30] DEBUG[3199] netsock2.c: Splitting '192.168.1.24' into... [Jan 22 18:10:30] DEBUG[3199] netsock2.c: ...host '192.168.1.24' and port ''. [Jan 22 18:10:30] DEBUG[3199] pjsip: icess0x9f4247c Candidate 0 added: comp_id=1, type=host, foundation=Hc0a80118, addr=192.168.1.24:15888, base=192.168.1.24:15888, prio=0x7effffff (2130706431) [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: RTP instance '0x9ee48ac' is setup and ready to go [Jan 22 18:10:30] DEBUG[3199] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x9ee48ac' [Jan 22 18:10:30] DEBUG[3199] netsock2.c: Splitting '192.168.1.24' into... [Jan 22 18:10:30] DEBUG[3199] netsock2.c: ...host '192.168.1.24' and port ''. [Jan 22 18:10:30] DEBUG[3199] pjsip: icess0x9f4247c Candidate 1 added: comp_id=2, type=host, foundation=Hc0a80118, addr=192.168.1.24:15889, base=192.168.1.24:15889, prio=0x7efffffe (2130706430) [Jan 22 18:10:30] DEBUG[3199] pjsip: icess0x9f4247c Destroying ICE session 0x9f4247c [Jan 22 18:10:30] DEBUG[3199] pjsip: stuse0xa061bc0 STUN session 0x9f14224 destroy request, ref_cnt=4 [Jan 22 18:10:30] DEBUG[3199] pjsip: stuse0x9c2cc50 STUN session 0x9f40e54 destroy request, ref_cnt=3 [Jan 22 18:10:30] DEBUG[3199] pjsip: ice_session.c ICE session 0x9f4247c destroyed [Jan 22 18:10:30] DEBUG[3199] pjsip: stun_session.c STUN session 0x9f14224 destroyed [Jan 22 18:10:30] DEBUG[3199] pjsip: stun_session.c STUN session 0x9f40e54 destroyed [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Setting tx payload type 0 based on m type on 0xb6231a48 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Setting tx payload type 8 based on m type on 0xb6231a48 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Setting tx payload type 18 based on m type on 0xb6231a48 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Setting tx payload type 4 based on m type on 0xb6231a48 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Don't have a default tx payload type 2 format for m type on 0xb6231a48 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Setting tx payload type 9 based on m type on 0xb6231a48 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Setting tx payload type 101 based on m type on 0xb6231a48 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Copying payload 0 (0x9c5c37c) from 0xb6231a48 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Copying payload 2 (0x9c65634) from 0xb6231a48 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Copying payload 4 (0x9f4bd64) from 0xb6231a48 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Copying payload 8 (0x9e86774) from 0xb6231a48 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Copying payload 9 (0xa33d014) from 0xb6231a48 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Copying payload 18 (0x9c540f4) from 0xb6231a48 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3199] rtp_engine.c: Copying payload 101 (0x9c5434c) from 0xb6231a48 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Media stream 'audio' handled by audio [Jan 22 18:10:30] DEBUG[3199] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Jan 22 18:10:30] DEBUG[3199] pjsip: endpoint .Response msg 100/INVITE/cseq=2 (tdta0x9ec44b0) created [Jan 22 18:10:30] DEBUG[3199] pjsip: dlg0x9ee697c .Initial answer Response msg 100/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Method is INVITE, Response is 100 Trying [Jan 22 18:10:30] DEBUG[3199] pjsip: inv0x9ee697c .Sending Response msg 100/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3199] pjsip: dlg0x9ee697c ..Sending Response msg 100/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3199] pjsip: tsx0x9c2c0a4 ..Sending Response msg 100/INVITE/cseq=2 (tdta0x9ec44b0) in state Trying [Jan 22 18:10:30] DEBUG[3199] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3199] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3199] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3199] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] VERBOSE[3199] res_pjsip_logger.c: <--- Transmitting SIP response (333 bytes) to UDP:192.168.1.13:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.13:5060;rport=5060;received=192.168.1.13;branch=z9hG4bK2526050211618511208 Call-ID: 139211552013260-127592499222417@192.168.1.13 From: "ALICE" ;tag=672914266 To: "123" CSeq: 2 INVITE Server: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:30] DEBUG[3199] pjsip: tsx0x9c2c0a4 ...State changed from Trying to Proceeding, event=TX_MSG [Jan 22 18:10:30] DEBUG[3199] pjsip: dlg0x9ee697c ....Transaction tsx0x9c2c0a4 state changed to Proceeding [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE()' [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c2c0a4) [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The current inv state is INCOMING [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Source of transaction state change is TX_MSG [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Sending response [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Method is INVITE, Response is 100 Trying [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE()' [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c2c0a4) [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x9c2c0a4 [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The current transaction state is Proceeding [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: The current inv state is INCOMING [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Sending response [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Method is INVITE, Response is 100 Trying [Jan 22 18:10:30] DEBUG[3199] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3199] pjsip: dlg0x9ee697c Module NAT added as dialog usage, data=(nil) [Jan 22 18:10:30] DEBUG[3199] chan_pjsip.c: Started PBX on new PJSIP channel PJSIP/ALICE-00000000 [Jan 22 18:10:30] DEBUG[3253][C-00000000] pbx.c: Launching 'NoOp' [Jan 22 18:10:30] DEBUG[3253][C-00000000] pbx.c: Result of 'EXTEN' is '123' [Jan 22 18:10:30] DEBUG[3253][C-00000000] pbx.c: Launching 'Dial' [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c UAC dialog created [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c Module Outbound INVITE Auth added as dialog usage, data=(nil) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c Module mod-invite added as dialog usage, data=0x9c2c85c [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c .Session count inc to 2 by mod-invite [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c Module mod-100rel added as dialog usage, data=0x9c2c95c [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c 100rel module attached [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0xa06184c UAC invite session created for dialog dlg0xa06184c [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c .Session count inc to 2 by Session Module [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c Module Session Module added as dialog usage, data=(nil) [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x9ec54ec' [Jan 22 18:10:30] DEBUG[3198] res_rtp_asterisk.c: Allocated port 11676 for RTP instance '0x9ec54ec' [Jan 22 18:10:30] DEBUG[3198] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:11676 (11676) for RTP instance '0x9ec54ec' [Jan 22 18:10:30] DEBUG[3198] pjsip: icess0x9c47bd4 ICE session created, comp_cnt=2, role is Unknown agent [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.24' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.24' and port ''. [Jan 22 18:10:30] DEBUG[3198] pjsip: icess0x9c47bd4 Candidate 0 added: comp_id=1, type=host, foundation=Hc0a80118, addr=192.168.1.24:11676, base=192.168.1.24:11676, prio=0x7effffff (2130706431) [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: RTP instance '0x9ec54ec' is setup and ready to go [Jan 22 18:10:30] DEBUG[3198] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x9ec54ec' [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.24' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.24' and port ''. [Jan 22 18:10:30] DEBUG[3198] pjsip: icess0x9c47bd4 Candidate 1 added: comp_id=2, type=host, foundation=Hc0a80118, addr=192.168.1.24:11677, base=192.168.1.24:11677, prio=0x7efffffe (2130706430) [Jan 22 18:10:30] DEBUG[3198] pjsip: icess0x9c47bd4 Destroying ICE session 0x9c47bd4 [Jan 22 18:10:30] DEBUG[3198] pjsip: stuse0x9c495d0 STUN session 0x9f4247c destroy request, ref_cnt=4 [Jan 22 18:10:30] DEBUG[3198] pjsip: stuse0x9f43048 STUN session 0x9f04a4c destroy request, ref_cnt=3 [Jan 22 18:10:30] DEBUG[3198] pjsip: ice_session.c ICE session 0x9c47bd4 destroyed [Jan 22 18:10:30] DEBUG[3198] pjsip: stun_session.c STUN session 0x9f4247c destroyed [Jan 22 18:10:30] DEBUG[3198] pjsip: stun_session.c STUN session 0x9f04a4c destroyed [Jan 22 18:10:30] DEBUG[3198] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint Request msg INVITE/cseq=15739 (tdta0xa05f200) created. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c Module NAT added as dialog usage, data=(nil) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c Module WebSocket Transport Module added as dialog usage, data=(nil) [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0xa06184c .Sending Request msg INVITE/cseq=15739 (tdta0xa05f200) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ..Sending Request msg INVITE/cseq=15739 (tdta0xa05f200) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ...Transaction created for Request msg INVITE/cseq=15738 (tdta0xa05f200) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ..Sending Request msg INVITE/cseq=15738 (tdta0xa05f200) in state Null [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_resolve.c ...Target '192.168.1.21:0' type=Unspecified resolved to '192.168.1.21:5060' type=UDP (UDP transport) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 192.168.1.24:5060 [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (913 bytes) to UDP:192.168.1.21:5060 ---> INVITE sip:123@192.168.1.21 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj89cfbb1a-1ce0-4479-a2d7-f7f47653577d From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: Contact: Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15738 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Type: application/sdp Content-Length: 261 v=0 o=- 1678942029 1678942029 IN IP4 192.168.1.24 s=Asterisk c=IN IP4 192.168.1.24 t=0 0 m=audio 11676 RTP/AVP 9 8 101 a=rtpmap:9 G722/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ...State changed from Null to Calling, event=TX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ....Transaction tsx0x9c48fa4 state changed to Calling [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CALLING [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Source of transaction state change is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending request [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Calling [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CALLING [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending request [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Response msg 100/INVITE/cseq=15738 (rdata0x9d1a91c) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP response (567 bytes) from UDP:192.168.1.21:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.24:5060;branch=z9hG4bKPj89cfbb1a-1ce0-4479-a2d7-f7f47653577d;received=192.168.1.24;rport=5060 From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15738 INVITE Server: Asterisk PBX 13.7.0-rc3 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Response msg 183/INVITE/cseq=15738 (rdata0x9d1a91c) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP response (884 bytes) from UDP:192.168.1.21:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 192.168.1.24:5060;branch=z9hG4bKPj89cfbb1a-1ce0-4479-a2d7-f7f47653577d;received=192.168.1.24;rport=5060 From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: ;tag=as7ef884fe Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15738 INVITE Server: Asterisk PBX 13.7.0-rc3 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 243 v=0 o=root 1043598828 1043598828 IN IP4 192.168.1.21 s=Asterisk PBX 13.7.0-rc3 c=IN IP4 192.168.1.21 t=0 0 m=audio 11080 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=sendrecv [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=15738 (rdata0x9d1a91c) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP response (870 bytes) from UDP:192.168.1.21:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.24:5060;branch=z9hG4bKPj89cfbb1a-1ce0-4479-a2d7-f7f47653577d;received=192.168.1.24;rport=5060 From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: ;tag=as7ef884fe Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15738 INVITE Server: Asterisk PBX 13.7.0-rc3 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Require: timer Content-Length: 243 v=0 o=root 1043598828 1043598828 IN IP4 192.168.1.21 s=Asterisk PBX 13.7.0-rc3 c=IN IP4 192.168.1.21 t=0 0 m=audio 11080 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=maxptime:150 a=sendrecv [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 100/INVITE/cseq=15738 (rdata0x9bb1ebc) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 .Incoming Response msg 100/INVITE/cseq=15738 (rdata0x9bb1ebc) in state Calling [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ..State changed from Calling to Proceeding, event=RX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Received Response msg 100/INVITE/cseq=15738 (rdata0x9bb1ebc) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Transaction tsx0x9c48fa4 state changed to Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CALLING [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Response is 100 Trying [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 183/INVITE/cseq=15738 (rdata0x9c781ec) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 .Incoming Response msg 183/INVITE/cseq=15738 (rdata0x9c781ec) in state Proceeding [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ..State changed from Proceeding to Proceeding, event=RX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Received Response msg 183/INVITE/cseq=15738 (rdata0x9c781ec) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ....Route-set updated [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Transaction tsx0x9c48fa4 state changed to Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is EARLY [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Source of transaction state change is RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Response is 183 Session Progress [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0xa06184c ....Got SDP answer in Response msg 183/INVITE/cseq=15738 (rdata0x9c781ec) [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0xa06184c ....SDP negotiation done, status=0 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.21' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.21' and port ''. [Jan 22 18:10:30] DEBUG[3198] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x9ec54ec' [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 8 based on m type on 0xb626d338 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 101 based on m type on 0xb626d338 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 8 (0x9f417dc) from 0xb626d338 to 0x9ec5698 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 101 (0x9e868ec) from 0xb626d338 to 0x9ec5698 [Jan 22 18:10:30] DEBUG[3198] channel.c: Channel PJSIP/LocalTrunk-00000001 setting read format path: alaw -> g722 [Jan 22 18:10:30] DEBUG[3198] channel.c: Channel PJSIP/LocalTrunk-00000001 setting write format path: g722 -> alaw [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is EARLY [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Response is 183 Session Progress [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 200/INVITE/cseq=15738 (rdata0x9c45754) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 .Incoming Response msg 200/INVITE/cseq=15738 (rdata0x9c45754) in state Proceeding [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ..State changed from Proceeding to Terminated, event=RX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Received Response msg 200/INVITE/cseq=15738 (rdata0x9c45754) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ....Route-set updated [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ....Route-set frozen [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Transaction tsx0x9c48fa4 state changed to Terminated [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONNECTING [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Source of transaction state change is RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Response is 200 OK [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0xa06184c ....SDP negotiation done, message body is ignored [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0xa06184c ....Received Response msg 200/INVITE/cseq=15738 (rdata0x9c45754), sending ACK [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint ....Request msg ACK/cseq=15738 (tdta0x9f2f0e8) created. [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c .....Sending Request msg ACK/cseq=15738 (tdta0x9f2f0e8) [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_resolve.c .....Target '192.168.1.21:5060' type=Unspecified resolved to '192.168.1.21:5060' type=UDP (UDP transport) [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (395 bytes) to UDP:192.168.1.21:5060 ---> ACK sip:123@192.168.1.21:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPjc99bbd27-d0a0-4506-abbf-10a0822d63f1 From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: ;tag=as7ef884fe Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15738 ACK Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending request [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is ACK [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Terminated [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Response is 200 OK [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c .SDP negotiation done, status=0 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3198] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x9ee48ac' [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 0 based on m type on 0xb626d588 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 8 based on m type on 0xb626d588 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 18 based on m type on 0xb626d588 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 4 based on m type on 0xb626d588 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Don't have a default tx payload type 2 format for m type on 0xb626d588 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 9 based on m type on 0xb626d588 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 101 based on m type on 0xb626d588 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 0 (0x9f0df7c) from 0xb626d588 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 2 (0x9f315a4) from 0xb626d588 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 4 (0x9c499bc) from 0xb626d588 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 8 (0x9c2d50c) from 0xb626d588 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 9 (0xa33d014) from 0xb626d588 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 18 (0x9f43b64) from 0xb626d588 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 101 (0x9f144e4) from 0xb626d588 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE, Response is 183 Session Progress [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c .Sending Response msg 183/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ..Sending Response msg 183/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ..Sending Response msg 183/INVITE/cseq=2 (tdta0x9ec44b0) in state Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 192.168.1.24:5060 [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP response (796 bytes) to UDP:192.168.1.13:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 192.168.1.13:5060;rport=5060;received=192.168.1.13;branch=z9hG4bK2526050211618511208 Call-ID: 139211552013260-127592499222417@192.168.1.13 From: "ALICE" ;tag=672914266 To: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 CSeq: 2 INVITE Server: Asterisk PBX 13.7.0 Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER Content-Type: application/sdp Content-Length: 233 v=0 o=- 26803467 17124302 IN IP4 192.168.1.24 s=Asterisk c=IN IP4 192.168.1.24 t=0 0 m=audio 15888 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ...State changed from Proceeding to Proceeding, event=TX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ....Transaction tsx0x9c2c0a4 state changed to Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c2c0a4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is EARLY [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Source of transaction state change is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE, Response is 183 Session Progress [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c2c0a4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x9c2c0a4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is EARLY [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE, Response is 183 Session Progress [Jan 22 18:10:30] DEBUG[3184] devicestate.c: No provider found, checking channel drivers for PJSIP - LocalTrunk [Jan 22 18:10:30] DEBUG[3184] devicestate.c: Changing state for PJSIP/LocalTrunk - state 2 (In use) [Jan 22 18:10:30] DEBUG[3247] app_queue.c: Device 'PJSIP/LocalTrunk' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 22 18:10:30] DEBUG[3253][C-00000000] channel.c: Channel PJSIP/LocalTrunk-00000001 setting read format path: alaw -> slin16 [Jan 22 18:10:30] DEBUG[3253][C-00000000] channel.c: Channel PJSIP/ALICE-00000000 setting write format path: slin16 -> g722 [Jan 22 18:10:30] DEBUG[3253][C-00000000] channel.c: Channel PJSIP/ALICE-00000000 setting read format path: g722 -> slin16 [Jan 22 18:10:30] DEBUG[3253][C-00000000] channel.c: Channel PJSIP/LocalTrunk-00000001 setting write format path: slin16 -> alaw [Jan 22 18:10:30] DEBUG[3184] devicestate.c: No provider found, checking channel drivers for PJSIP - ALICE [Jan 22 18:10:30] DEBUG[3184] devicestate.c: Changing state for PJSIP/ALICE - state 2 (In use) [Jan 22 18:10:30] DEBUG[3247] app_queue.c: Device 'PJSIP/ALICE' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE, Response is 200 OK [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c .Sending Response msg 200/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ..Sending Response msg 200/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ..Sending Response msg 200/INVITE/cseq=2 (tdta0x9ec44b0) in state Proceeding [Jan 22 18:10:30] DEBUG[3198] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 192.168.1.24:5060 [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP response (830 bytes) to UDP:192.168.1.13:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.13:5060;rport=5060;received=192.168.1.13;branch=z9hG4bK2526050211618511208 Call-ID: 139211552013260-127592499222417@192.168.1.13 From: "ALICE" ;tag=672914266 To: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 CSeq: 2 INVITE Server: Asterisk PBX 13.7.0 Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER Contact: Supported: 100rel, timer, replaces, norefersub Content-Type: application/sdp Content-Length: 233 v=0 o=- 26803467 17124302 IN IP4 192.168.1.24 s=Asterisk c=IN IP4 192.168.1.24 t=0 0 m=audio 15888 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ...State changed from Proceeding to Completed, event=TX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ....Transaction tsx0x9c2c0a4 state changed to Completed [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c2c0a4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONNECTING [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Source of transaction state change is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE, Response is 200 OK [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c2c0a4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x9c2c0a4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Completed [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONNECTING [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE, Response is 200 OK [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge_native_rtp.c: Bridge 'bbad1d9e-3997-4873-a7cc-90204529b775' can not use native RTP bridge as two channels are required [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Chose bridge technology simple_bridge [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling simple_bridge technology constructor [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling simple_bridge technology start [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f39934(PJSIP/LocalTrunk-00000001) is joining [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: pushing 0x9f39934(PJSIP/LocalTrunk-00000001) [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge_native_rtp.c: Bridge 'bbad1d9e-3997-4873-a7cc-90204529b775' can not use native RTP bridge as two channels are required [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Chose bridge technology simple_bridge [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775 is already using the new technology. [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f39934(PJSIP/LocalTrunk-00000001) is joining simple_bridge technology [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f317d4(PJSIP/ALICE-00000000) is joining [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: pushing 0x9f317d4(PJSIP/ALICE-00000000) [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Chose bridge technology native_rtp [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling native_rtp technology constructor [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: moving 0x9f39934(PJSIP/LocalTrunk-00000001) to dummy bridge temporarily [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f39934(PJSIP/LocalTrunk-00000001) is leaving simple_bridge technology (dummy) [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling simple_bridge technology stop [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f317d4(PJSIP/ALICE-00000000) is joining native_rtp technology [Jan 22 18:10:30] DEBUG[3253][C-00000000] chan_pjsip.c: RTP changed on PJSIP/ALICE-00000000; initiating direct media update [Jan 22 18:10:30] DEBUG[3253][C-00000000] chan_pjsip.c: RTP changed on PJSIP/LocalTrunk-00000001; initiating direct media update [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f39934(PJSIP/LocalTrunk-00000001) is joining native_rtp technology [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling native_rtp technology start [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling simple_bridge technology destructor [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Chose bridge technology native_rtp [Jan 22 18:10:30] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775 is already using the new technology. [Jan 22 18:10:30] DEBUG[3190] cdr.c: Finalized CDR for PJSIP/LocalTrunk-00000001 - start 1453507830.666294 answer 1453507830.675160 end 1453507830.680001 dispo ANSWERED [Jan 22 18:10:30] DEBUG[3177] threadpool.c: Increasing threadpool stasis-core's size by 1 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Delay sending reinvite to ALICE because of outstanding transaction... [Jan 22 18:10:30] DEBUG[3198] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint .Request msg INVITE/cseq=15740 (tdta0x9f300f0) created. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to LocalTrunk [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0xa06184c .Sending Request msg INVITE/cseq=15740 (tdta0x9f300f0) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ..Sending Request msg INVITE/cseq=15740 (tdta0x9f300f0) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9f8a984 ...Transaction created for Request msg INVITE/cseq=15739 (tdta0x9f300f0) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9f8a984 ..Sending Request msg INVITE/cseq=15739 (tdta0x9f300f0) in state Null [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_resolve.c ...Target '192.168.1.21:5060' type=Unspecified resolved to '192.168.1.21:5060' type=UDP (UDP transport) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 192.168.1.24:5060 [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (923 bytes) to UDP:192.168.1.21:5060 ---> INVITE sip:123@192.168.1.21:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj02f9aba4-a061-41a6-b81b-dd3c997b84f8 From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: ;tag=as7ef884fe Contact: Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15739 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800;refresher=uas Min-SE: 90 Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Type: application/sdp Content-Length: 237 v=0 o=- 1678942029 1678942030 IN IP4 192.168.1.24 s=Asterisk c=IN IP4 192.168.1.13 t=0 0 m=audio 10018 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9f8a984 ...State changed from Null to Calling, event=TX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ....Transaction tsx0x9f8a984 state changed to Calling [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9f8a984) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9f8a984 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Calling [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending request [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge technology softmix does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Chose bridge technology native_rtp [Jan 22 18:10:30] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775 is already using the new technology. [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c48fa4 Timeout timer event [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c48fa4 .State changed from Terminated to Destroyed, event=TIMER [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c48fa4 Transaction destroyed! [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Response msg 488/INVITE/cseq=15739 (rdata0x9d1a91c) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP response (557 bytes) from UDP:192.168.1.21:5060 ---> SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 192.168.1.24:5060;branch=z9hG4bKPj02f9aba4-a061-41a6-b81b-dd3c997b84f8;received=192.168.1.24;rport=5060 From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: ;tag=as7ef884fe Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15739 INVITE Server: Asterisk PBX 13.7.0-rc3 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Session-Expires: 1800;refresher=uas Content-Length: 0 [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 488/INVITE/cseq=15739 (rdata0x9c2fe64) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9f8a984 .Incoming Response msg 488/INVITE/cseq=15739 (rdata0x9c2fe64) in state Calling [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint ..Request msg ACK/cseq=15739 (tdta0x9f87d30) created. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (395 bytes) to UDP:192.168.1.21:5060 ---> ACK sip:123@192.168.1.21:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj02f9aba4-a061-41a6-b81b-dd3c997b84f8 From: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a To: ;tag=as7ef884fe Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 15739 ACK Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9f8a984 ..State changed from Calling to Completed, event=RX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Received Response msg 488/INVITE/cseq=15739 (rdata0x9c2fe64) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0xa06184c ...Transaction tsx0x9f8a984 state changed to Completed [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9f8a984 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Completed [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Response is 488 Not acceptable here [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: reINVITE received final response code 488 [Jan 22 18:10:30] DEBUG[3198] pjsip: tdta0x9f300f0 ..Destroying txdata Request msg INVITE/cseq=15739 (tdta0x9f300f0) [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Request msg ACK/cseq=2 (rdata0x9d1a91c) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP request (372 bytes) from UDP:192.168.1.13:5060 ---> ACK sip:192.168.1.24:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.13:5060;branch=z9hG4bK1057481961150924835 From: ALICE ;tag=672914266 To: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 2 ACK Max-Forwards: 70 User-Agent: Voip Phone 1.0 Content-Length: 0 [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Request msg ACK/cseq=2 (rdata0x9f686fc) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c .Received Request msg ACK/cseq=2 (rdata0x9f686fc) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ..Request to terminate transaction [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c2c0a4 ...State changed from Completed to Terminated, event=USER [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ....Transaction tsx0x9c2c0a4 state changed to Terminated [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAS INVITE transaction involved in this state change is 0x9c2c0a4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Terminated [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is USER [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONNECTING [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Endpoint 'ALICE(PJSIP/ALICE-00000000)' INVITE delay check. tsx-state:Terminated [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received request [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is ACK [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Endpoint 'ALICE(PJSIP/ALICE-00000000)' sending delayed INVITE request. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint .Request msg INVITE/cseq=24772 (tdta0x9c4fa80) created. [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to ALICE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c .Sending Request msg INVITE/cseq=24772 (tdta0x9c4fa80) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ..Sending Request msg INVITE/cseq=24772 (tdta0x9c4fa80) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ...Transaction created for Request msg INVITE/cseq=24771 (tdta0x9c4fa80) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ..Sending Request msg INVITE/cseq=24771 (tdta0x9c4fa80) in state Null [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_resolve.c ...Target '192.168.1.13:5060' type=Unspecified resolved to '192.168.1.13:5060' type=UDP (UDP transport) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 192.168.1.24:5060 [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (911 bytes) to UDP:192.168.1.13:5060 ---> INVITE sip:ALICE@192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj669d4bf8-4748-4af0-8e1a-0f80646c0755 From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Contact: Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24771 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Type: application/sdp Content-Length: 233 v=0 o=- 26803467 17124303 IN IP4 192.168.1.24 s=Asterisk c=IN IP4 192.168.1.21 t=0 0 m=audio 11080 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ...State changed from Null to Calling, event=TX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ....Transaction tsx0x9c48fa4 state changed to Calling [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Calling [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Sending request [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c2c0a4 Timeout timer event [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c2c0a4 .State changed from Terminated to Destroyed, event=TIMER [Jan 22 18:10:30] DEBUG[3203] pjsip: tdta0x9ec44b0 ..Destroying txdata Response msg 200/INVITE/cseq=2 (tdta0x9ec44b0) [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c2c0a4 Transaction destroyed! [Jan 22 18:10:30] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=24771 (rdata0x9d1a91c) [Jan 22 18:10:30] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP response (770 bytes) from UDP:192.168.1.13:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj669d4bf8-4748-4af0-8e1a-0f80646c0755 From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24771 INVITE Contact: Supported: 100rel, replaces, timer Server: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 214 v=0 o=BOB 69312114 16415161 IN IP4 192.168.1.13 s=A conversation c=IN IP4 192.168.1.13 t=0 0 m=audio 10018 RTP/AVP 9 101 a=rtpmap:9 G722/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 200/INVITE/cseq=24771 (rdata0x9edbf54) [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 .Incoming Response msg 200/INVITE/cseq=24771 (rdata0x9edbf54) in state Calling [Jan 22 18:10:30] DEBUG[3198] pjsip: tsx0x9c48fa4 ..State changed from Calling to Terminated, event=RX_MSG [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ...Received Response msg 200/INVITE/cseq=24771 (rdata0x9edbf54) [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c ...Transaction tsx0x9c48fa4 state changed to Terminated [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c ....Got SDP answer in Response msg 200/INVITE/cseq=24771 (rdata0x9edbf54) [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c ....SDP negotiation done, status=0 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Applying negotiated SDP media stream 'audio' using audio SDP handler [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port ''. [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 9 based on m type on 0xb626d2d8 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Setting tx payload type 101 based on m type on 0xb626d2d8 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 9 (0xa33d014) from 0xb626d2d8 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] rtp_engine.c: Copying payload 101 (0x9c540f4) from 0xb626d2d8 to 0x9ee4a58 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Applied negotiated SDP media stream 'audio' using audio SDP handler [Jan 22 18:10:30] DEBUG[3198] pjsip: inv0x9ee697c ....Received Response msg 200/INVITE/cseq=24771 (rdata0x9edbf54), sending ACK [Jan 22 18:10:30] DEBUG[3198] pjsip: endpoint ....Request msg ACK/cseq=24771 (tdta0x9f300f0) created. [Jan 22 18:10:30] DEBUG[3198] pjsip: dlg0x9ee697c .....Sending Request msg ACK/cseq=24771 (tdta0x9f300f0) [Jan 22 18:10:30] DEBUG[3198] pjsip: sip_resolve.c .....Target '192.168.1.13:5060' type=Unspecified resolved to '192.168.1.13:5060' type=UDP (UDP transport) [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:30] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:30] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:30] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (410 bytes) to UDP:192.168.1.13:5060 ---> ACK sip:ALICE@192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj6c585bf6-3098-4c32-b6ab-13ced19aa4c0 From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24771 ACK Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current transaction state is Terminated [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:30] DEBUG[3198] res_pjsip_session.c: Response is 200 OK [Jan 22 18:10:30] DEBUG[3253][C-00000000] channel.c: Dropping duplicate answer! [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c48fa4 Timeout timer event [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c48fa4 .State changed from Terminated to Destroyed, event=TIMER [Jan 22 18:10:30] DEBUG[3203] pjsip: tdta0x9c4fa80 ..Destroying txdata Request msg INVITE/cseq=24771 (tdta0x9c4fa80) [Jan 22 18:10:30] DEBUG[3203] pjsip: tsx0x9c48fa4 Transaction destroyed! [Jan 22 18:10:31] DEBUG[3254][C-00000000] res_rtp_asterisk.c: 0x9f20bb0 -- Probation learning mode pass with source address 192.168.1.21:11080 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3253][C-00000000] res_rtp_asterisk.c: Ooh, format changed from none to g722 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:31] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:32] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:33] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:34] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:35] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:36] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:37] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:38] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:39] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:40] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:41] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:42] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:43] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:44] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:45] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:46] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:47] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:48] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3202] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 22 18:10:49] DEBUG[3201] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 22 18:10:49] DEBUG[3200] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 22 18:10:49] DEBUG[3197] threadpool.c: Destroying worker thread 9 [Jan 22 18:10:49] DEBUG[3197] threadpool.c: Destroying worker thread 8 [Jan 22 18:10:49] DEBUG[3197] threadpool.c: Destroying worker thread 7 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3206] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 22 18:10:49] DEBUG[3176] threadpool.c: Destroying worker thread 12 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:49] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3252] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 22 18:10:50] DEBUG[3255] threadpool.c: Worker thread idle timeout reached. Dying. [Jan 22 18:10:50] DEBUG[3177] threadpool.c: Destroying worker thread 13 [Jan 22 18:10:50] DEBUG[3177] threadpool.c: Destroying worker thread 14 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:50] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:51] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3203] pjsip: tsx0x9d730c4 Timeout timer event [Jan 22 18:10:52] DEBUG[3203] pjsip: tsx0x9d730c4 .State changed from Completed to Terminated, event=TIMER [Jan 22 18:10:52] DEBUG[3203] pjsip: tsx0x9d730c4 Timeout timer event [Jan 22 18:10:52] DEBUG[3203] pjsip: tsx0x9d730c4 .State changed from Terminated to Destroyed, event=TIMER [Jan 22 18:10:52] DEBUG[3203] pjsip: tdta0x9f37c58 ..Destroying txdata Response msg 200/REGISTER/cseq=2 (tdta0x9f37c58) [Jan 22 18:10:52] DEBUG[3203] pjsip: tsx0x9d730c4 Transaction destroyed! [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:52] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:53] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:54] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:55] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:56] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:57] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:58] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3254][C-00000000] translate.c: Sample size different 160 vs 320 [Jan 22 18:10:59] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Request msg BYE/cseq=102 (rdata0x9d1a91c) [Jan 22 18:10:59] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP request (444 bytes) from UDP:192.168.1.21:5060 ---> BYE sip:asterisk@192.168.1.24:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.21:5060;branch=z9hG4bK5012e50a;rport Max-Forwards: 70 From: ;tag=as7ef884fe To: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a Call-ID: 454768bc-cf90-457a-97f5-078522346296 CSeq: 102 BYE User-Agent: Asterisk PBX 13.7.0-rc3 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 [Jan 22 18:10:59] DEBUG[3203] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:59] DEBUG[3203] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:59] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:59] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:59] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Request msg BYE/cseq=102 (rdata0x9f6c62c) [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0xa06184c .Received Request msg BYE/cseq=102 (rdata0x9f6c62c) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9d730c4 ...Transaction created for Request msg BYE/cseq=102 (rdata0x9f6c62c) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9d730c4 ..Incoming Request msg BYE/cseq=102 (rdata0x9f6c62c) in state Null [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9d730c4 ...State changed from Null to Trying, event=RX_MSG [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0xa06184c ....Transaction tsx0x9d730c4 state changed to Trying [Jan 22 18:10:59] DEBUG[3198] pjsip: endpoint .....Response msg 200/BYE/cseq=102 (tdta0x9c4fa80) created [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0xa06184c ......Sending Response msg 200/BYE/cseq=102 (tdta0x9c4fa80) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9d730c4 ......Sending Response msg 200/BYE/cseq=102 (tdta0x9c4fa80) in state Trying [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '192.168.1.21:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '192.168.1.21' and port '5060'. [Jan 22 18:10:59] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP response (345 bytes) to UDP:192.168.1.21:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.21:5060;rport=5060;received=192.168.1.21;branch=z9hG4bK5012e50a Call-ID: 454768bc-cf90-457a-97f5-078522346296 From: ;tag=as7ef884fe To: "ALICE" ;tag=16a56f3b-3363-44d1-8c6c-d6ae87e8095a CSeq: 102 BYE Server: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9d730c4 .......State changed from Trying to Completed, event=TX_MSG [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0xa06184c ........Transaction tsx0x9d730c4 state changed to Completed [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x9d730c4 [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current transaction state is Completed [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Sending response [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Method is BYE, Response is 200 OK [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is DISCONNCTD [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Source of transaction state change is RX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Received request [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Method is BYE [Jan 22 18:10:59] DEBUG[3177] threadpool.c: Increasing threadpool stasis-core's size by 1 [Jan 22 18:10:59] DEBUG[3198] pjsip: tdta0x9f2f0e8 .....Destroying txdata Request msg ACK/cseq=15738 (tdta0x9f2f0e8) [Jan 22 18:10:59] DEBUG[3198] pjsip: tdta0xa05f200 .....Destroying txdata Request msg INVITE/cseq=15738 (tdta0xa05f200) [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0xa06184c ......Session count dec to 4 by mod-invite [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk(PJSIP/LocalTrunk-00000001)' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The UAS BYE transaction involved in this state change is 0x9d730c4 [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current transaction state is Completed [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is DISCONNCTD [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge_channel.c: Setting 0x9f39934(PJSIP/LocalTrunk-00000001) state from:0 to:1 [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: pulling 0x9f39934(PJSIP/LocalTrunk-00000001) [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f39934(PJSIP/LocalTrunk-00000001) is leaving native_rtp technology [Jan 22 18:10:59] DEBUG[3254][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x9ee48ac' [Jan 22 18:10:59] DEBUG[3254][C-00000000] chan_pjsip.c: RTP changed on PJSIP/ALICE-00000000; initiating direct media update [Jan 22 18:10:59] DEBUG[3198] res_pjsip_t38.c: Not creating outgoing SDP stream: T.38 not enabled [Jan 22 18:10:59] DEBUG[3198] pjsip: endpoint .Request msg INVITE/cseq=24773 (tdta0x9f37c58) created. [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Sending session refresh SDP via re-INVITE to ALICE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:59] DEBUG[3254][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x9ec54ec' [Jan 22 18:10:59] DEBUG[3254][C-00000000] chan_pjsip.c: RTP changed on PJSIP/LocalTrunk-00000001; initiating direct media update [Jan 22 18:10:59] DEBUG[3199] res_pjsip_session.c: Not sending reinvite to LocalTrunk because of disconnected state... [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge_native_rtp.c: Discontinued RTP bridging of 'PJSIP/ALICE-00000000' and 'PJSIP/LocalTrunk-00000001' - media will flow through Asterisk core [Jan 22 18:10:59] DEBUG[3198] pjsip: inv0x9ee697c .Sending Request msg INVITE/cseq=24773 (tdta0x9f37c58) [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ..Sending Request msg INVITE/cseq=24773 (tdta0x9f37c58) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c48fa4 ...Transaction created for Request msg INVITE/cseq=24772 (tdta0x9f37c58) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c48fa4 ..Sending Request msg INVITE/cseq=24772 (tdta0x9f37c58) in state Null [Jan 22 18:10:59] DEBUG[3198] pjsip: sip_resolve.c ...Target '192.168.1.13:5060' type=Unspecified resolved to '192.168.1.13:5060' type=UDP (UDP transport) [Jan 22 18:10:59] DEBUG[3198] res_pjsip_multihomed.c: Re-wrote Contact URI host/port to 192.168.1.24:5060 [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:59] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (911 bytes) to UDP:192.168.1.13:5060 ---> INVITE sip:ALICE@192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj909335b1-bcb1-4344-a5de-1de0277122f2 From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Contact: Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24772 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REGISTER, REFER Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Type: application/sdp Content-Length: 233 v=0 o=- 26803467 17124304 IN IP4 192.168.1.24 s=Asterisk c=IN IP4 192.168.1.24 t=0 0 m=audio 15888 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c48fa4 ...State changed from Null to Calling, event=TX_MSG [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ....Transaction tsx0x9c48fa4 state changed to Calling [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE(PJSIP/ALICE-00000000)' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session still has an invite_tsx (0x9c48fa4) [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current transaction state is Calling [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Sending request [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Method is INVITE [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: dissolving bridge with cause 16(Normal Clearing) [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge_channel.c: Setting 0x9f317d4(PJSIP/ALICE-00000000) state from:0 to:2 [Jan 22 18:10:59] DEBUG[3177] threadpool.c: Increasing threadpool stasis-core's size by 1 [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: queueing action type:13 sub:1001 [Jan 22 18:10:59] DEBUG[3190] cdr.c: Finalized CDR for PJSIP/ALICE-00000000 - start 1453507830.663910 answer 1453507830.677592 end 1453507859.262813 dispo ANSWERED [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775 is dissolved, not performing smart bridge operation. [Jan 22 18:10:59] DEBUG[3253][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: pulling 0x9f317d4(PJSIP/ALICE-00000000) [Jan 22 18:10:59] DEBUG[3253][C-00000000] bridge_channel.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: 0x9f317d4(PJSIP/ALICE-00000000) is leaving native_rtp technology [Jan 22 18:10:59] DEBUG[3253][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775 is dissolved, not performing smart bridge operation. [Jan 22 18:10:59] DEBUG[3253][C-00000000] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Jan 22 18:10:59] DEBUG[3253][C-00000000] pbx.c: Spawn extension (users,123,2) exited non-zero on 'PJSIP/ALICE-00000000' [Jan 22 18:10:59] DEBUG[3253][C-00000000] channel.c: Soft-Hanging (0x10) up channel 'PJSIP/ALICE-00000000' [Jan 22 18:10:59] DEBUG[3253][C-00000000] channel.c: Hanging up channel 'PJSIP/ALICE-00000000' [Jan 22 18:10:59] DEBUG[3253][C-00000000] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP) [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Delay sending BYE to ALICE because of outstanding transaction... [Jan 22 18:10:59] DEBUG[3190] cdr_radius.c: Unable to create RADIUS record. CDR not recorded! [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: About to query table structure: SELECT sql FROM sqlite_master WHERE type='table' AND tbl_name='ast_cdr' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: id INTEGER [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: clid VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: src VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: dst VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: dcontext VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: channel VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: dstchannel VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: lastapp VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: lastdata VARCHAR(80) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: start DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: answer DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: end DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: duration INT(11) NOT NULL DEFAULT 0 [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: billsec INT(11) NOT NULL DEFAULT 0 [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: disposition VARCHAR(45) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: amaflags INT(11) NOT NULL DEFAULT 0 [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: accountcode VARCHAR(20) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: uniqueid VARCHAR(32) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: userfield VARCHAR(255) NOT NULL DEFAULT '' [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: Found field: PRIMARY KEY (id) [Jan 22 18:10:59] DEBUG[3190] res_config_sqlite.c: SQL query: INSERT INTO ast_cdr (clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,start,answer,end,duration,billsec,disposition,amaflags,uniqueid) VALUES ('"ALICE" ','ALICE','123','users','PJSIP/ALICE-00000000','PJSIP/LocalTrunk-00000001','Dial','PJSIP/123@LocalTrunk,60,','2016-01-22 18:10:30','2016-01-22 18:10:30','2016-01-22 18:10:59','28','28','ANSWERED','DOCUMENTATION','1453507830.0') [Jan 22 18:10:59] DEBUG[3184] devicestate.c: No provider found, checking channel drivers for PJSIP - ALICE [Jan 22 18:10:59] DEBUG[3184] devicestate.c: Changing state for PJSIP/ALICE - state 1 (Not in use) [Jan 22 18:10:59] DEBUG[3247] app_queue.c: Device 'PJSIP/ALICE' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: actually destroying basic bridge, nobody wants it anymore [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling basic bridge destructor [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling native_rtp technology stop [Jan 22 18:10:59] DEBUG[3254][C-00000000] bridge.c: Bridge bbad1d9e-3997-4873-a7cc-90204529b775: calling native_rtp technology destructor [Jan 22 18:10:59] DEBUG[3254][C-00000000] channel.c: Hanging up channel 'PJSIP/LocalTrunk-00000001' [Jan 22 18:10:59] DEBUG[3254][C-00000000] chan_pjsip.c: AST hangup cause 16 (no match found in PJSIP) [Jan 22 18:10:59] DEBUG[3184] devicestate.c: No provider found, checking channel drivers for PJSIP - LocalTrunk [Jan 22 18:10:59] DEBUG[3184] devicestate.c: Changing state for PJSIP/LocalTrunk - state 1 (Not in use) [Jan 22 18:10:59] DEBUG[3247] app_queue.c: Device 'PJSIP/LocalTrunk' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jan 22 18:10:59] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=24772 (rdata0x9d1a91c) [Jan 22 18:10:59] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP response (770 bytes) from UDP:192.168.1.13:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj909335b1-bcb1-4344-a5de-1de0277122f2 From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24772 INVITE Contact: Supported: 100rel, replaces, timer Server: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 214 v=0 o=BOB 13322969 17888230 IN IP4 192.168.1.13 s=A conversation c=IN IP4 192.168.1.13 t=0 0 m=audio 10018 RTP/AVP 9 101 a=rtpmap:9 G722/16000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv [Jan 22 18:10:59] DEBUG[3203] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:59] DEBUG[3203] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:59] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:59] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:59] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 200/INVITE/cseq=24772 (rdata0x9bb1ebc) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c48fa4 .Incoming Response msg 200/INVITE/cseq=24772 (rdata0x9bb1ebc) in state Calling [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c48fa4 ..State changed from Calling to Terminated, event=RX_MSG [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ...Received Response msg 200/INVITE/cseq=24772 (rdata0x9bb1ebc) [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ...Transaction tsx0x9c48fa4 state changed to Terminated [Jan 22 18:10:59] DEBUG[3198] pjsip: inv0x9ee697c ....Got SDP answer in Response msg 200/INVITE/cseq=24772 (rdata0x9bb1ebc) [Jan 22 18:10:59] DEBUG[3198] pjsip: inv0x9ee697c ....SDP negotiation done, status=0 [Jan 22 18:10:59] DEBUG[3198] pjsip: inv0x9ee697c ....Received Response msg 200/INVITE/cseq=24772 (rdata0x9bb1ebc), sending ACK [Jan 22 18:10:59] DEBUG[3198] pjsip: tdta0x9f300f0 ....Destroying txdata Request msg ACK/cseq=24771 (tdta0x9f300f0) [Jan 22 18:10:59] DEBUG[3198] pjsip: endpoint ....Request msg ACK/cseq=24772 (tdta0x9f300f0) created. [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c .....Sending Request msg ACK/cseq=24772 (tdta0x9f300f0) [Jan 22 18:10:59] DEBUG[3198] pjsip: sip_resolve.c .....Target '192.168.1.13:5060' type=Unspecified resolved to '192.168.1.13:5060' type=UDP (UDP transport) [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:59] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (410 bytes) to UDP:192.168.1.13:5060 ---> ACK sip:ALICE@192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj00b9e693-a244-4e3b-8141-50b872fa160b From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24772 ACK Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE()' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9c48fa4 [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current transaction state is Terminated [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Response is 200 OK [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Endpoint 'ALICE()' INVITE delay check. tsx-state:Terminated [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Endpoint 'ALICE()' sending delayed BYE request. [Jan 22 18:10:59] DEBUG[3198] pjsip: endpoint .Request msg BYE/cseq=24774 (tdta0xa05f200) created. [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Method is BYE [Jan 22 18:10:59] DEBUG[3198] pjsip: inv0x9ee697c .Sending Request msg BYE/cseq=24774 (tdta0xa05f200) [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ..Sending Request msg BYE/cseq=24774 (tdta0xa05f200) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c2c0a4 ...Transaction created for Request msg BYE/cseq=24773 (tdta0xa05f200) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c2c0a4 ..Sending Request msg BYE/cseq=24773 (tdta0xa05f200) in state Null [Jan 22 18:10:59] DEBUG[3198] pjsip: sip_resolve.c ...Target '192.168.1.13:5060' type=Unspecified resolved to '192.168.1.13:5060' type=UDP (UDP transport) [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:59] DEBUG[3198] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:59] DEBUG[3198] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:59] VERBOSE[3198] res_pjsip_logger.c: <--- Transmitting SIP request (410 bytes) to UDP:192.168.1.13:5060 ---> BYE sip:ALICE@192.168.1.13:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj7eab9676-817a-49b9-a1d1-1931e8708d07 From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24773 BYE Max-Forwards: 70 User-Agent: Asterisk PBX 13.7.0 Content-Length: 0 [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c2c0a4 ...State changed from Null to Calling, event=TX_MSG [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ....Transaction tsx0x9c2c0a4 state changed to Calling [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE()' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x9c2c0a4 [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current transaction state is Calling [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The transaction state change event is TX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is CONFIRMED [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Sending request [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Method is BYE [Jan 22 18:10:59] DEBUG[3203] pjsip: tsx0x9c48fa4 Timeout timer event [Jan 22 18:10:59] DEBUG[3203] pjsip: tsx0x9c48fa4 .State changed from Terminated to Destroyed, event=TIMER [Jan 22 18:10:59] DEBUG[3203] pjsip: tdta0x9f37c58 ..Destroying txdata Request msg INVITE/cseq=24772 (tdta0x9f37c58) [Jan 22 18:10:59] DEBUG[3203] pjsip: tsx0x9c48fa4 Transaction destroyed! [Jan 22 18:10:59] DEBUG[3190] cdr.c: CDR for PJSIP/LocalTrunk-00000001 is dialed and has no Party B; discarding [Jan 22 18:10:59] DEBUG[3203] pjsip: sip_endpoint.c Processing incoming message: Response msg 200/BYE/cseq=24773 (rdata0x9d1a91c) [Jan 22 18:10:59] VERBOSE[3203] res_pjsip_logger.c: <--- Received SIP response (357 bytes) from UDP:192.168.1.13:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.1.24:5060;rport;branch=z9hG4bKPj7eab9676-817a-49b9-a1d1-1931e8708d07 From: "123" ;tag=34cd55aa-9df1-48ce-a2dd-12b9311243f8 To: "ALICE" ;tag=672914266 Call-ID: 139211552013260-127592499222417@192.168.1.13 CSeq: 24773 BYE Server: Voip Phone 1.0 Content-Length: 0 [Jan 22 18:10:59] DEBUG[3203] netsock2.c: Splitting '192.168.1.13:5060' into... [Jan 22 18:10:59] DEBUG[3203] netsock2.c: ...host '192.168.1.13' and port '5060'. [Jan 22 18:10:59] DEBUG[3203] netsock2.c: Splitting '0.0.0.0:5060' into... [Jan 22 18:10:59] DEBUG[3203] netsock2.c: ...host '0.0.0.0' and port '5060'. [Jan 22 18:10:59] DEBUG[3198] pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 200/BYE/cseq=24773 (rdata0x9edbf54) [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c2c0a4 .Incoming Response msg 200/BYE/cseq=24773 (rdata0x9edbf54) in state Calling [Jan 22 18:10:59] DEBUG[3198] pjsip: tsx0x9c2c0a4 ..State changed from Calling to Completed, event=RX_MSG [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ...Received Response msg 200/BYE/cseq=24773 (rdata0x9edbf54) [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c ...Transaction tsx0x9c2c0a4 state changed to Completed [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE()' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: There is no transaction involved in this state change [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is DISCONNCTD [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Source of transaction state change is RX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Response is 200 OK [Jan 22 18:10:59] DEBUG[3198] pjsip: tdta0x9f300f0 ....Destroying txdata Request msg ACK/cseq=24772 (tdta0x9f300f0) [Jan 22 18:10:59] DEBUG[3198] pjsip: dlg0x9ee697c .....Session count dec to 2 by mod-invite [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE()' [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x9c2c0a4 [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current transaction state is Completed [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The transaction state change event is RX_MSG [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: The current inv state is DISCONNCTD [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Received response [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: Response is 200 OK [Jan 22 18:10:59] DEBUG[3198] res_pjsip_session.c: BYE received final response code 200 [Jan 22 18:11:02] DEBUG[3203] pjsip: tsx0x9f8a984 Timeout timer event [Jan 22 18:11:02] DEBUG[3203] pjsip: tsx0x9f8a984 .State changed from Completed to Terminated, event=TIMER [Jan 22 18:11:02] DEBUG[3203] pjsip: dlg0xa06184c ..Transaction tsx0x9f8a984 state changed to Terminated [Jan 22 18:11:02] DEBUG[3203] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:11:02] DEBUG[3203] res_pjsip_session.c: The state change pertains to the endpoint 'LocalTrunk()' [Jan 22 18:11:02] DEBUG[3203] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:11:02] DEBUG[3203] res_pjsip_session.c: The UAC INVITE transaction involved in this state change is 0x9f8a984 [Jan 22 18:11:02] DEBUG[3203] res_pjsip_session.c: The current transaction state is Terminated [Jan 22 18:11:02] DEBUG[3203] res_pjsip_session.c: The transaction state change event is TIMER [Jan 22 18:11:02] DEBUG[3203] res_pjsip_session.c: The current inv state is DISCONNCTD [Jan 22 18:11:02] DEBUG[3198] res_pjsip_session.c: Destroying SIP session with endpoint LocalTrunk [Jan 22 18:11:02] DEBUG[3198] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x9ec54ec' [Jan 22 18:11:02] DEBUG[3198] rtp_engine.c: Destroyed RTP instance '0x9ec54ec' [Jan 22 18:11:02] DEBUG[3198] pjsip: dlg0xa06184c .Session count dec to 0 by Session Module [Jan 22 18:11:02] DEBUG[3198] taskprocessor.c: destroying taskprocessor '2862e61c-6a87-4293-b99a-b84aadc042b4' [Jan 22 18:11:02] DEBUG[3203] pjsip: tsx0x9f8a984 Timeout timer event [Jan 22 18:11:02] DEBUG[3203] pjsip: tsx0x9f8a984 .State changed from Terminated to Destroyed, event=TIMER [Jan 22 18:11:02] DEBUG[3203] pjsip: tdta0x9f87d30 ..Destroying txdata Request msg ACK/cseq=15739 (tdta0x9f87d30) [Jan 22 18:11:02] DEBUG[3203] pjsip: tsx0x9f8a984 Transaction destroyed! [Jan 22 18:11:04] DEBUG[3203] pjsip: tsx0x9c2c0a4 Timeout timer event [Jan 22 18:11:04] DEBUG[3203] pjsip: tsx0x9c2c0a4 .State changed from Completed to Terminated, event=TIMER [Jan 22 18:11:04] DEBUG[3203] pjsip: dlg0x9ee697c ..Transaction tsx0x9c2c0a4 state changed to Terminated [Jan 22 18:11:04] DEBUG[3203] res_pjsip_session.c: Function session_inv_on_tsx_state_changed called on event TSX_STATE [Jan 22 18:11:04] DEBUG[3203] res_pjsip_session.c: The state change pertains to the endpoint 'ALICE()' [Jan 22 18:11:04] DEBUG[3203] res_pjsip_session.c: The inv session does NOT have an invite_tsx [Jan 22 18:11:04] DEBUG[3203] res_pjsip_session.c: The UAC BYE transaction involved in this state change is 0x9c2c0a4 [Jan 22 18:11:04] DEBUG[3203] res_pjsip_session.c: The current transaction state is Terminated [Jan 22 18:11:04] DEBUG[3203] res_pjsip_session.c: The transaction state change event is TIMER [Jan 22 18:11:04] DEBUG[3203] res_pjsip_session.c: The current inv state is DISCONNCTD [Jan 22 18:11:04] DEBUG[3198] res_pjsip_session.c: Destroying SIP session with endpoint ALICE [Jan 22 18:11:04] DEBUG[3198] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x9ee48ac' [Jan 22 18:11:04] DEBUG[3198] rtp_engine.c: Destroyed RTP instance '0x9ee48ac' [Jan 22 18:11:04] DEBUG[3198] pjsip: dlg0x9ee697c .Session count dec to 0 by Session Module [Jan 22 18:11:04] DEBUG[3198] pjsip: dlg0x9ee697c .Dialog destroyed [Jan 22 18:11:04] DEBUG[3198] taskprocessor.c: destroying taskprocessor '5607deac-978d-4c78-b3c7-5e0080c80dcd' [Jan 22 18:11:04] DEBUG[3203] pjsip: tsx0x9c2c0a4 Timeout timer event [Jan 22 18:11:04] DEBUG[3203] pjsip: tsx0x9c2c0a4 .State changed from Terminated to Destroyed, event=TIMER [Jan 22 18:11:04] DEBUG[3203] pjsip: tdta0xa05f200 ..Destroying txdata Request msg BYE/cseq=24773 (tdta0xa05f200) [Jan 22 18:11:04] DEBUG[3203] pjsip: tsx0x9c2c0a4 Transaction destroyed! [Jan 22 18:11:05] DEBUG[3251] cdr.c: CDR Engine termination request received; waiting on messages... [Jan 22 18:11:05] DEBUG[3251] taskprocessor.c: destroying taskprocessor 'ast_msg_queue' [Jan 22 18:11:05] VERBOSE[3251] asterisk.c: Asterisk cleanly ending (0). [Jan 22 18:11:05] VERBOSE[3251] asterisk.c: Executing last minute cleanups [Jan 22 18:11:05] DEBUG[3251] res_musiconhold.c: Destroying MOH class 'default' [Jan 22 18:11:05] DEBUG[3251] taskprocessor.c: destroying taskprocessor 'b7b15612-ee30-40c4-8381-ab795b5c5d60' [Jan 22 18:11:05] DEBUG[3251] asterisk.c: Asterisk ending (0).