[asterisk-bugs] [JIRA] (ASTERISK-26583) pjsip transports stored in db

Jeff Clay (JIRA) noreply at issues.asterisk.org
Fri Nov 11 11:05:10 CST 2016


Jeff Clay created ASTERISK-26583:
------------------------------------

             Summary: pjsip transports stored in db
                 Key: ASTERISK-26583
                 URL: https://issues.asterisk.org/jira/browse/ASTERISK-26583
             Project: Asterisk
          Issue Type: Bug
      Security Level: None
          Components: pjproject/pjsip, Resources/res_config_odbc
    Affects Versions: 14.1.1, 13.12.2
         Environment: Centos 7.2.1511
kernel 3.10.0-327.36.3.el7
            Reporter: Jeff Clay


When using pjsip transports in mariadb table via odbc traffic from asterisk is not seen on the network. For example, when a phone attempts to register, the registration request is seen in pjsip debug as well as a tcpdump; then when asterisk replies with the 401 auth challenge that is only seen in the pjsip debug and is not seen in a tcpdump and the endpoint never receives the challenge. 

Using transports within pjsip.conf works properly with the exact same transport settings that were failing when used in database.

Full configs, db tables, tcpdump and debug logs below.

*config files*
{code:title=sorcery.conf}
[res_pjsip]
endpoint=realtime,ps_endpoints
auth=realtime,ps_auths
transport=realtime,ps_transports
aor=realtime,ps_aors
domain_alias=realtime,ps_domain_aliases

[res_pjsip_endpoint_identifier_ip]
identify=realtime,ps_endpoint_id_ips
system=realtime,ps_systems
{code}


{code:title=extconfig.conf}
[settings]
iaxusers => odbc,asterisk,iaxfriends
iaxpeers => odbc,asterisk,iaxfriends
iaxfriends => odbc,asterisk
ps_endpoints => odbc,asterisk
ps_auths => odbc,asterisk
ps_aors => odbc,asterisk
ps_domain_aliases => odbc,asterisk
ps_endpoint_id_ips => odbc,asterisk
ps_globals => odbc,asterisk
ps_transports => odbc,asterisk
ps_systems => odbc,asterisk
ps_subscription_persistence => odbc,asterisk
ps_registrations => odbc,asterisk
ps_contacts => odbc,asterisk
voicemail => odbc,asterisk
extensions => odbc,asterisk
meetme => odbc,asterisk
queues => odbc,asterisk
queue_members => odbc,asterisk
queue_rules => odbc,asterisk
meetme => odbc,asterisk
followme => odbc,asterisk,followme
followme_numbers => odbc,asterisk,followme_numbers
{code}

*database tables*
{code:title=ps_aors}
INSERT INTO `ps_aors` (`id`, `contact`, `default_expiration`, `mailboxes`, `max_contacts`, `minimum_expiration`, `remove_existing`, `qualify_frequency`, `authenticate_qualify`, `maximum_expiration`, `outbound_proxy`, `support_path`, `qualify_timeout`, `voicemail_extension`)
VALUES
	('1506', NULL, NULL, NULL, 3, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL);
{code}

{code:title=ps_auths}
INSERT INTO `ps_auths` (`id`, `auth_type`, `nonce_lifetime`, `md5_cred`, `password`, `realm`, `username`)
VALUES
	('phone', 'userpass', NULL, NULL, 'pass', NULL, '1506');
{code}

{code:title=ps_endpoints}
INSERT INTO `ps_endpoints` (`id`, `transport`, `aors`, `auth`, `context`, `disallow`, `allow`, `direct_media`, `connected_line_method`, `direct_media_method`, `direct_media_glare_mitigation`, `disable_direct_media_on_nat`, `dtmf_mode`, `external_media_address`, `force_rport`, `ice_support`, `identify_by`, `mailboxes`, `moh_suggest`, `outbound_auth`, `outbound_proxy`, `rewrite_contact`, `rtp_ipv6`, `rtp_symmetric`, `send_diversion`, `send_pai`, `send_rpid`, `timers_min_se`, `timers`, `timers_sess_expires`, `callerid`, `callerid_privacy`, `callerid_tag`, `100rel`, `aggregate_mwi`, `trust_id_inbound`, `trust_id_outbound`, `use_ptime`, `use_avpf`, `media_encryption`, `inband_progress`, `call_group`, `pickup_group`, `named_call_group`, `named_pickup_group`, `device_state_busy_at`, `fax_detect`, `t38_udptl`, `t38_udptl_ec`, `t38_udptl_maxdatagram`, `t38_udptl_nat`, `t38_udptl_ipv6`, `tone_zone`, `language`, `one_touch_recording`, `record_on_feature`, `record_off_feature`, `rtp_engine`, `allow_transfer`, `allow_subscribe`, `sdp_owner`, `sdp_session`, `tos_audio`, `tos_video`, `sub_min_expiry`, `from_domain`, `from_user`, `mwi_from_user`, `dtls_verify`, `dtls_rekey`, `dtls_cert_file`, `dtls_private_key`, `dtls_cipher`, `dtls_ca_file`, `dtls_ca_path`, `dtls_setup`, `srtp_tag_32`, `media_address`, `redirect_method`, `set_var`, `cos_audio`, `cos_video`, `message_context`, `force_avp`, `media_use_received_transport`, `accountcode`, `media_encryption_optimistic`, `user_eq_phone`, `rpid_immediate`, `g726_non_standard`, `rtp_keepalive`, `rtp_timeout`, `rtp_timeout_hold`, `bind_rtp_to_media_address`, `voicemail_extension`, `mwi_subscribe_replaces_unsolicited`, `deny`, `permit`, `acl`, `contact_deny`, `contact_permit`, `contact_acl`, `subscribe_context`, `fax_detect_timeout`, `contact_user`)
VALUES
	('1506', 'nat_udp', '1506', 'phone', 'ADMIN', 'all', 'ulaw', 'no', NULL, NULL, NULL, NULL, 'rfc4733', NULL, 'yes', NULL, 'username', '1506', NULL, NULL, NULL, 'yes', 'no', 'yes', NULL, NULL, NULL, NULL, NULL, NULL, '<1506> \"Jeff\"', NULL, NULL, NULL, 'yes', NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, '1506', NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL, NULL);
{code}

{code:title=ps_transports}
INSERT INTO `ps_transports` (`id`, `async_operations`, `bind`, `ca_list_file`, `cert_file`, `cipher`, `domain`, `external_media_address`, `external_signaling_address`, `external_signaling_port`, `method`, `local_net`, `password`, `priv_key_file`, `protocol`, `require_client_cert`, `verify_client`, `verify_server`, `tos`, `cos`, `allow_reload`)
VALUES
	('nat_udp', NULL, '0.0.0.0', NULL, NULL, NULL, NULL, '104.197.69.7', '104.197.69.7', NULL, NULL, '10.128.0.0/20', NULL, NULL, 'udp', NULL, NULL, NULL, NULL, NULL, 'yes');
{code}


*DEBUG*

You can see here that asterisk receives the register request and sends the auth challenge.

{code:title=asterisk debug with pjsip debug}
devast*CLI> core set debug 5
Core debug was OFF and is now 5.
[Nov 11 16:34:50] DEBUG[18040]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:34:50] DEBUG[18028]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 2
[Nov 11 16:34:53] DEBUG[18038]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:34:53] DEBUG[18039]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:34:53] DEBUG[18028]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 0
[Nov 11 16:34:53] DEBUG[18028]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 1
devast*CLI> pjsip set logger on
PJSIP Logging enabled
[Nov 11 16:35:00] DEBUG[18092]: res_pjsip_registrar_expire.c:78 check_expiration_thread: Woke up at 1478882100  Interval: 30
[Nov 11 16:35:00] DEBUG[18092]: res_pjsip_registrar_expire.c:85 check_expiration_thread: Expiring 0 contacts

[Nov 11 16:35:19] DEBUG[18090]: res_timing_timerfd.c:167 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 3 instead
[Nov 11 16:35:23] DEBUG[18090]: res_timing_timerfd.c:167 timerfd_timer_ack: Expected to acknowledge 1 ticks but got 3 instead
[Nov 11 16:35:30] DEBUG[18057]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:30] DEBUG[18058]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:30] DEBUG[18051]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 8
[Nov 11 16:35:30] DEBUG[18051]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 9
[Nov 11 16:35:30] DEBUG[18060]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:30] DEBUG[18061]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:30] DEBUG[18062]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:30] DEBUG[18027]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 11
[Nov 11 16:35:30] DEBUG[18027]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 12
[Nov 11 16:35:30] DEBUG[18027]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 13
[Nov 11 16:35:30] DEBUG[18092]: res_pjsip_registrar_expire.c:78 check_expiration_thread: Woke up at 1478882130  Interval: 30
[Nov 11 16:35:30] DEBUG[18092]: res_pjsip_registrar_expire.c:85 check_expiration_thread: Expiring 0 contacts

[Nov 11 16:35:30] DEBUG[18059]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:30] DEBUG[18027]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 10
[Nov 11 16:35:33] DEBUG[18055]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:33] DEBUG[18056]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:33] DEBUG[18051]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 6
[Nov 11 16:35:33] DEBUG[18051]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 7
[Nov 11 16:35:33] DEBUG[18054]: threadpool.c:1137 worker_idle: Worker thread idle timeout reached. Dying.
[Nov 11 16:35:33] DEBUG[18051]: threadpool.c:996 worker_thread_destroy: Destroying worker thread 5
<--- Received SIP request (525 bytes) from UDP:12.49.160.131:58781 --->
REGISTER sip:104.197.69.7 SIP/2.0
Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
Max-Forwards: 70
Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
To: <sip:1506 at 104.197.69.7>
From: <sip:1506 at 104.197.69.7>;tag=bc201339
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
CSeq: 1 REGISTER
Expires: 3600
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
User-Agent: Bria 4 release 4.6.0 stamp 82162
Content-Length: 0


[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:47] DEBUG[18053]: res_pjsip/pjsip_distributor.c:267 find_dialog: Could not find matching transaction for Request msg REGISTER/cseq=1 (rdata0x1d09188)
[Nov 11 16:35:47] DEBUG[18053]: res_pjsip/pjsip_distributor.c:359 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002a to use for Request msg REGISTER/cseq=1 (rdata0x1d09188)
[Nov 11 16:35:47] DEBUG[18051]: threadpool.c:517 grow: Increasing threadpool SIP's size by 5
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id LIKE') = '%'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_pjsip_endpoint_identifier_ip.c:128 ip_identify: No identify sections to match against
[Nov 11 16:35:47] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:133 username_identify: Attempting identify by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506 at 104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_transports WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'nat_udp'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 65535] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3689 ast_parse_arg: extract int from [100] in [1, 2147483647] gives [100](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.0' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.0' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: acl.c:661 ast_append_ha: 10.128.0.0:0/255.255.240.0:0 sense 0 appended to ACL
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '104.197.69.7' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '104.197.69.7' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_sorcery_realtime.c:131 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:145 username_identify: Identified by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'phone'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [32] in [0, 4294967295] gives [32](0)
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
<--- Transmitting SIP response (531 bytes) to UDP:12.49.160.131:58781 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.200.101.116:58781;rport=58781;received=12.49.160.131;branch=z9hG4bK-524287-1---510c701eed52f364
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
From: <sip:1506 at 104.197.69.7>;tag=bc201339
To: <sip:1506 at 104.197.69.7>;tag=z9hG4bK-524287-1---510c701eed52f364
CSeq: 1 REGISTER
WWW-Authenticate: Digest  realm="asterisk",nonce="1478882147/8b845d8c881a2d5056e3cfeab5623a50",opaque="38df36b365ce5435",algorithm=md5,qop="auth"
Server: Asterisk PBX 14.1.1
Content-Length:  0


<--- Received SIP request (525 bytes) from UDP:12.49.160.131:58781 --->
REGISTER sip:104.197.69.7 SIP/2.0
Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
Max-Forwards: 70
Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
To: <sip:1506 at 104.197.69.7>
From: <sip:1506 at 104.197.69.7>;tag=bc201339
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
CSeq: 1 REGISTER
Expires: 3600
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
User-Agent: Bria 4 release 4.6.0 stamp 82162
Content-Length: 0


[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:47] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:47] DEBUG[18053]: res_pjsip/pjsip_distributor.c:267 find_dialog: Could not find matching transaction for Request msg REGISTER/cseq=1 (rdata0x7f34940095b8)
[Nov 11 16:35:47] DEBUG[18053]: res_pjsip/pjsip_distributor.c:359 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002a to use for Request msg REGISTER/cseq=1 (rdata0x7f34940095b8)
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id LIKE') = '%'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_pjsip_endpoint_identifier_ip.c:128 ip_identify: No identify sections to match against
[Nov 11 16:35:47] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:133 username_identify: Attempting identify by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506 at 104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_transports WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'nat_udp'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 65535] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3689 ast_parse_arg: extract int from [100] in [1, 2147483647] gives [100](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.0' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.0' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: acl.c:661 ast_append_ha: 10.128.0.0:0/255.255.240.0:0 sense 0 appended to ACL
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '104.197.69.7' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '104.197.69.7' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: res_sorcery_realtime.c:131 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:47] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:145 username_identify: Identified by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[Nov 11 16:35:47] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'phone'
[Nov 11 16:35:47] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:47] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [32] in [0, 4294967295] gives [32](0)
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port ''.
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:47] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
<--- Transmitting SIP response (531 bytes) to UDP:12.49.160.131:58781 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.200.101.116:58781;rport=58781;received=12.49.160.131;branch=z9hG4bK-524287-1---510c701eed52f364
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
From: <sip:1506 at 104.197.69.7>;tag=bc201339
To: <sip:1506 at 104.197.69.7>;tag=z9hG4bK-524287-1---510c701eed52f364
CSeq: 1 REGISTER
WWW-Authenticate: Digest  realm="asterisk",nonce="1478882147/8b845d8c881a2d5056e3cfeab5623a50",opaque="611806151c5b8d57",algorithm=md5,qop="auth"
Server: Asterisk PBX 14.1.1
Content-Length:  0


<--- Received SIP request (525 bytes) from UDP:12.49.160.131:58781 --->
REGISTER sip:104.197.69.7 SIP/2.0
Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
Max-Forwards: 70
Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
To: <sip:1506 at 104.197.69.7>
From: <sip:1506 at 104.197.69.7>;tag=bc201339
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
CSeq: 1 REGISTER
Expires: 3600
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
User-Agent: Bria 4 release 4.6.0 stamp 82162
Content-Length: 0


[Nov 11 16:35:48] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:48] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
[Nov 11 16:35:48] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:48] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:48] DEBUG[18053]: res_pjsip/pjsip_distributor.c:267 find_dialog: Could not find matching transaction for Request msg REGISTER/cseq=1 (rdata0x7f34940095b8)
[Nov 11 16:35:48] DEBUG[18053]: res_pjsip/pjsip_distributor.c:359 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002a to use for Request msg REGISTER/cseq=1 (rdata0x7f34940095b8)
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id LIKE') = '%'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:48] DEBUG[18116]: res_pjsip_endpoint_identifier_ip.c:128 ip_identify: No identify sections to match against
[Nov 11 16:35:48] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:133 username_identify: Attempting identify by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506 at 104.197.69.7'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '104.197.69.7'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_transports WHERE id = ?
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'nat_udp'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 65535] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3689 ast_parse_arg: extract int from [100] in [1, 2147483647] gives [100](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.0' into...
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.0' and port ''.
[Nov 11 16:35:48] DEBUG[18116]: acl.c:661 ast_append_ha: 10.128.0.0:0/255.255.240.0:0 sense 0 appended to ACL
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '104.197.69.7' into...
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '104.197.69.7' and port ''.
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:48] DEBUG[18116]: res_sorcery_realtime.c:131 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:48] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:145 username_identify: Identified by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[Nov 11 16:35:48] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'phone'
[Nov 11 16:35:48] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:48] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [32] in [0, 4294967295] gives [32](0)
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3' into...
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port ''.
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131' into...
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port ''.
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:48] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
<--- Transmitting SIP response (531 bytes) to UDP:12.49.160.131:58781 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.200.101.116:58781;rport=58781;received=12.49.160.131;branch=z9hG4bK-524287-1---510c701eed52f364
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
From: <sip:1506 at 104.197.69.7>;tag=bc201339
To: <sip:1506 at 104.197.69.7>;tag=z9hG4bK-524287-1---510c701eed52f364
CSeq: 1 REGISTER
WWW-Authenticate: Digest  realm="asterisk",nonce="1478882148/653fbe68f2075d95afa47c426cc5968e",opaque="332b66cd0eb86fdf",algorithm=md5,qop="auth"
Server: Asterisk PBX 14.1.1
Content-Length:  0


<--- Received SIP request (525 bytes) from UDP:12.49.160.131:58781 --->
REGISTER sip:104.197.69.7 SIP/2.0
Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
Max-Forwards: 70
Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
To: <sip:1506 at 104.197.69.7>
From: <sip:1506 at 104.197.69.7>;tag=bc201339
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
CSeq: 1 REGISTER
Expires: 3600
Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
User-Agent: Bria 4 release 4.6.0 stamp 82162
Content-Length: 0


[Nov 11 16:35:50] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:50] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
[Nov 11 16:35:50] DEBUG[18053]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:50] DEBUG[18053]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:50] DEBUG[18053]: res_pjsip/pjsip_distributor.c:267 find_dialog: Could not find matching transaction for Request msg REGISTER/cseq=1 (rdata0x7f34940095b8)
[Nov 11 16:35:50] DEBUG[18053]: res_pjsip/pjsip_distributor.c:359 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002a to use for Request msg REGISTER/cseq=1 (rdata0x7f34940095b8)
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id LIKE') = '%'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:50] DEBUG[18116]: res_pjsip_endpoint_identifier_ip.c:128 ip_identify: No identify sections to match against
[Nov 11 16:35:50] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:133 username_identify: Attempting identify by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506 at 104.197.69.7'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '104.197.69.7'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_transports WHERE id = ?
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'nat_udp'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 65535] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3689 ast_parse_arg: extract int from [100] in [1, 2147483647] gives [100](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.0' into...
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.0' and port ''.
[Nov 11 16:35:50] DEBUG[18116]: acl.c:661 ast_append_ha: 10.128.0.0:0/255.255.240.0:0 sense 0 appended to ACL
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '104.197.69.7' into...
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '104.197.69.7' and port ''.
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = '1506'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:50] DEBUG[18116]: res_sorcery_realtime.c:131 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[Nov 11 16:35:50] DEBUG[18116]: res_pjsip_endpoint_identifier_user.c:145 username_identify: Identified by From username '1506' domain '104.197.69.7'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:869 _ast_odbc_request_obj2: Reusing ODBC handle 0x253ae80 from class 'asterisk'
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:116 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[Nov 11 16:35:50] DEBUG[18116]: res_config_odbc.c:132 custom_prepare: Parameter 1 ('id') = 'phone'
[Nov 11 16:35:50] DEBUG[18116]: res_odbc.c:718 ast_odbc_release_obj: Releasing ODBC handle 0x253ae80 into pool
[Nov 11 16:35:50] DEBUG[18116]: config.c:3742 ast_parse_arg: extract uint from [32] in [0, 4294967295] gives [32](0)
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3' into...
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port ''.
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131' into...
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port ''.
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '10.128.0.3:5060' into...
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '10.128.0.3' and port '5060'.
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:172 ast_sockaddr_split_hostport: Splitting '12.49.160.131:58781' into...
[Nov 11 16:35:50] DEBUG[18116]: netsock2.c:226 ast_sockaddr_split_hostport: ...host '12.49.160.131' and port '58781'.
<--- Transmitting SIP response (531 bytes) to UDP:12.49.160.131:58781 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 10.200.101.116:58781;rport=58781;received=12.49.160.131;branch=z9hG4bK-524287-1---510c701eed52f364
Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
From: <sip:1506 at 104.197.69.7>;tag=bc201339
To: <sip:1506 at 104.197.69.7>;tag=z9hG4bK-524287-1---510c701eed52f364
CSeq: 1 REGISTER
WWW-Authenticate: Digest  realm="asterisk",nonce="1478882150/cc54c3c70bf899a0db1ec63be86a0597",opaque="0f7b7b9136d9bd05",algorithm=md5,qop="auth"
Server: Asterisk PBX 14.1.1
Content-Length:  0


devast*CLI> pjsip set logger off
PJSIP Logging disabled
[Nov 11 16:36:00] DEBUG[18092]: res_pjsip_registrar_expire.c:78 check_expiration_thread: Woke up at 1478882160  Interval: 30
[Nov 11 16:36:00] DEBUG[18092]: res_pjsip_registrar_expire.c:85 check_expiration_thread: Expiring 0 contacts

devast*CLI> core set debug off
Core debug was 5 and is now OFF.
devast*CLI> 
{code}


And you can see here that the auth challenge sent by Asterisk is never actually seen on the network.

{code:title=tcpdump}
[root at devast asterisk]# tcpdump -i any udp -vvvvv
tcpdump: listening on any, link-type LINUX_SLL (Linux cooked), capture size 65535 bytes
16:35:47.369178 IP (tos 0x0, ttl 53, id 46691, offset 0, flags [none], proto UDP (17), length 553)
    12.49.160.131.58781 > devast.c.cnp1-143601.internal.sip: [udp sum ok] SIP, length: 525
	REGISTER sip:104.197.69.7 SIP/2.0
	Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
	Max-Forwards: 70
	Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
	To: <sip:1506 at 104.197.69.7>
	From: <sip:1506 at 104.197.69.7>;tag=bc201339
	Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
	CSeq: 1 REGISTER
	Expires: 3600
	Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
	User-Agent: Bria 4 release 4.6.0 stamp 82162
	Content-Length: 0
	
	
	0x0000:  5245 4749 5354 4552 2073 6970 3a31 3034
	0x0010:  2e31 3937 2e36 392e 3720 5349 502f 322e
	0x0020:  300d 0a56 6961 3a20 5349 502f 322e 302f
	0x0030:  5544 5020 3130 2e32 3030 2e31 3031 2e31
	0x0040:  3136 3a35 3837 3831 3b62 7261 6e63 683d
	0x0050:  7a39 6847 3462 4b2d 3532 3432 3837 2d31
	0x0060:  2d2d 2d35 3130 6337 3031 6565 6435 3266
	0x0070:  3336 343b 7270 6f72 740d 0a4d 6178 2d46
	0x0080:  6f72 7761 7264 733a 2037 300d 0a43 6f6e
	0x0090:  7461 6374 3a20 3c73 6970 3a31 3530 3640
	0x00a0:  3130 2e32 3030 2e31 3031 2e31 3136 3a35
	0x00b0:  3837 3831 3b72 696e 7374 616e 6365 3d31
	0x00c0:  6337 6362 3531 6439 6634 3634 6564 333e
	0x00d0:  0d0a 546f 3a20 3c73 6970 3a31 3530 3640
	0x00e0:  3130 342e 3139 372e 3639 2e37 3e0d 0a46
	0x00f0:  726f 6d3a 203c 7369 703a 3135 3036 4031
	0x0100:  3034 2e31 3937 2e36 392e 373e 3b74 6167
	0x0110:  3d62 6332 3031 3333 390d 0a43 616c 6c2d
	0x0120:  4944 3a20 3832 3136 324d 6a41 354e 5445
	0x0130:  3059 6a6b 795a 5445 344d 6a41 335a 4755
	0x0140:  7a4e 7a5a 6859 6a51 784e 4463 324d 546c
	0x0150:  6c4d 7a49 0d0a 4353 6571 3a20 3120 5245
	0x0160:  4749 5354 4552 0d0a 4578 7069 7265 733a
	0x0170:  2033 3630 300d 0a41 6c6c 6f77 3a20 5355
	0x0180:  4253 4352 4942 452c 204e 4f54 4946 592c
	0x0190:  2049 4e56 4954 452c 2041 434b 2c20 4341
	0x01a0:  4e43 454c 2c20 4259 452c 2052 4546 4552
	0x01b0:  2c20 494e 464f 2c20 4f50 5449 4f4e 532c
	0x01c0:  204d 4553 5341 4745 0d0a 5573 6572 2d41
	0x01d0:  6765 6e74 3a20 4272 6961 2034 2072 656c
	0x01e0:  6561 7365 2034 2e36 2e30 2073 7461 6d70
	0x01f0:  2038 3231 3632 0d0a 436f 6e74 656e 742d
	0x0200:  4c65 6e67 7468 3a20 300d 0a0d 0a
16:35:47.374595 IP (tos 0x0, ttl 64, id 42673, offset 0, flags [DF], proto UDP (17), length 72)
    localhost.41841 > localhost.domain: [bad udp cksum 0xfe47 -> 0xcda6!] 40893+ PTR? 131.160.49.12.in-addr.arpa. (44)
16:35:47.374628 IP (tos 0x0, ttl 64, id 42675, offset 0, flags [DF], proto UDP (17), length 72)
    localhost.60441 > localhost.domain: [bad udp cksum 0xfe47 -> 0x84fe!] 40893+ PTR? 131.160.49.12.in-addr.arpa. (44)
16:35:47.872889 IP (tos 0x0, ttl 53, id 19187, offset 0, flags [none], proto UDP (17), length 553)
    12.49.160.131.58781 > devast.c.cnp1-143601.internal.sip: [udp sum ok] SIP, length: 525
	REGISTER sip:104.197.69.7 SIP/2.0
	Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
	Max-Forwards: 70
	Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
	To: <sip:1506 at 104.197.69.7>
	From: <sip:1506 at 104.197.69.7>;tag=bc201339
	Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
	CSeq: 1 REGISTER
	Expires: 3600
	Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
	User-Agent: Bria 4 release 4.6.0 stamp 82162
	Content-Length: 0
	
	
	0x0000:  5245 4749 5354 4552 2073 6970 3a31 3034
	0x0010:  2e31 3937 2e36 392e 3720 5349 502f 322e
	0x0020:  300d 0a56 6961 3a20 5349 502f 322e 302f
	0x0030:  5544 5020 3130 2e32 3030 2e31 3031 2e31
	0x0040:  3136 3a35 3837 3831 3b62 7261 6e63 683d
	0x0050:  7a39 6847 3462 4b2d 3532 3432 3837 2d31
	0x0060:  2d2d 2d35 3130 6337 3031 6565 6435 3266
	0x0070:  3336 343b 7270 6f72 740d 0a4d 6178 2d46
	0x0080:  6f72 7761 7264 733a 2037 300d 0a43 6f6e
	0x0090:  7461 6374 3a20 3c73 6970 3a31 3530 3640
	0x00a0:  3130 2e32 3030 2e31 3031 2e31 3136 3a35
	0x00b0:  3837 3831 3b72 696e 7374 616e 6365 3d31
	0x00c0:  6337 6362 3531 6439 6634 3634 6564 333e
	0x00d0:  0d0a 546f 3a20 3c73 6970 3a31 3530 3640
	0x00e0:  3130 342e 3139 372e 3639 2e37 3e0d 0a46
	0x00f0:  726f 6d3a 203c 7369 703a 3135 3036 4031
	0x0100:  3034 2e31 3937 2e36 392e 373e 3b74 6167
	0x0110:  3d62 6332 3031 3333 390d 0a43 616c 6c2d
	0x0120:  4944 3a20 3832 3136 324d 6a41 354e 5445
	0x0130:  3059 6a6b 795a 5445 344d 6a41 335a 4755
	0x0140:  7a4e 7a5a 6859 6a51 784e 4463 324d 546c
	0x0150:  6c4d 7a49 0d0a 4353 6571 3a20 3120 5245
	0x0160:  4749 5354 4552 0d0a 4578 7069 7265 733a
	0x0170:  2033 3630 300d 0a41 6c6c 6f77 3a20 5355
	0x0180:  4253 4352 4942 452c 204e 4f54 4946 592c
	0x0190:  2049 4e56 4954 452c 2041 434b 2c20 4341
	0x01a0:  4e43 454c 2c20 4259 452c 2052 4546 4552
	0x01b0:  2c20 494e 464f 2c20 4f50 5449 4f4e 532c
	0x01c0:  204d 4553 5341 4745 0d0a 5573 6572 2d41
	0x01d0:  6765 6e74 3a20 4272 6961 2034 2072 656c
	0x01e0:  6561 7365 2034 2e36 2e30 2073 7461 6d70
	0x01f0:  2038 3231 3632 0d0a 436f 6e74 656e 742d
	0x0200:  4c65 6e67 7468 3a20 300d 0a0d 0a
16:35:48.876407 IP (tos 0x0, ttl 53, id 41254, offset 0, flags [none], proto UDP (17), length 553)
    12.49.160.131.58781 > devast.c.cnp1-143601.internal.sip: [udp sum ok] SIP, length: 525
	REGISTER sip:104.197.69.7 SIP/2.0
	Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
	Max-Forwards: 70
	Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
	To: <sip:1506 at 104.197.69.7>
	From: <sip:1506 at 104.197.69.7>;tag=bc201339
	Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
	CSeq: 1 REGISTER
	Expires: 3600
	Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
	User-Agent: Bria 4 release 4.6.0 stamp 82162
	Content-Length: 0
	
	
	0x0000:  5245 4749 5354 4552 2073 6970 3a31 3034
	0x0010:  2e31 3937 2e36 392e 3720 5349 502f 322e
	0x0020:  300d 0a56 6961 3a20 5349 502f 322e 302f
	0x0030:  5544 5020 3130 2e32 3030 2e31 3031 2e31
	0x0040:  3136 3a35 3837 3831 3b62 7261 6e63 683d
	0x0050:  7a39 6847 3462 4b2d 3532 3432 3837 2d31
	0x0060:  2d2d 2d35 3130 6337 3031 6565 6435 3266
	0x0070:  3336 343b 7270 6f72 740d 0a4d 6178 2d46
	0x0080:  6f72 7761 7264 733a 2037 300d 0a43 6f6e
	0x0090:  7461 6374 3a20 3c73 6970 3a31 3530 3640
	0x00a0:  3130 2e32 3030 2e31 3031 2e31 3136 3a35
	0x00b0:  3837 3831 3b72 696e 7374 616e 6365 3d31
	0x00c0:  6337 6362 3531 6439 6634 3634 6564 333e
	0x00d0:  0d0a 546f 3a20 3c73 6970 3a31 3530 3640
	0x00e0:  3130 342e 3139 372e 3639 2e37 3e0d 0a46
	0x00f0:  726f 6d3a 203c 7369 703a 3135 3036 4031
	0x0100:  3034 2e31 3937 2e36 392e 373e 3b74 6167
	0x0110:  3d62 6332 3031 3333 390d 0a43 616c 6c2d
	0x0120:  4944 3a20 3832 3136 324d 6a41 354e 5445
	0x0130:  3059 6a6b 795a 5445 344d 6a41 335a 4755
	0x0140:  7a4e 7a5a 6859 6a51 784e 4463 324d 546c
	0x0150:  6c4d 7a49 0d0a 4353 6571 3a20 3120 5245
	0x0160:  4749 5354 4552 0d0a 4578 7069 7265 733a
	0x0170:  2033 3630 300d 0a41 6c6c 6f77 3a20 5355
	0x0180:  4253 4352 4942 452c 204e 4f54 4946 592c
	0x0190:  2049 4e56 4954 452c 2041 434b 2c20 4341
	0x01a0:  4e43 454c 2c20 4259 452c 2052 4546 4552
	0x01b0:  2c20 494e 464f 2c20 4f50 5449 4f4e 532c
	0x01c0:  204d 4553 5341 4745 0d0a 5573 6572 2d41
	0x01d0:  6765 6e74 3a20 4272 6961 2034 2072 656c
	0x01e0:  6561 7365 2034 2e36 2e30 2073 7461 6d70
	0x01f0:  2038 3231 3632 0d0a 436f 6e74 656e 742d
	0x0200:  4c65 6e67 7468 3a20 300d 0a0d 0a
16:35:50.876637 IP (tos 0x0, ttl 53, id 61840, offset 0, flags [none], proto UDP (17), length 553)
    12.49.160.131.58781 > devast.c.cnp1-143601.internal.sip: [udp sum ok] SIP, length: 525
	REGISTER sip:104.197.69.7 SIP/2.0
	Via: SIP/2.0/UDP 10.200.101.116:58781;branch=z9hG4bK-524287-1---510c701eed52f364;rport
	Max-Forwards: 70
	Contact: <sip:1506 at 10.200.101.116:58781;rinstance=1c7cb51d9f464ed3>
	To: <sip:1506 at 104.197.69.7>
	From: <sip:1506 at 104.197.69.7>;tag=bc201339
	Call-ID: 82162MjA5NTE0YjkyZTE4MjA3ZGUzNzZhYjQxNDc2MTllMzI
	CSeq: 1 REGISTER
	Expires: 3600
	Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
	User-Agent: Bria 4 release 4.6.0 stamp 82162
	Content-Length: 0
	
	
	0x0000:  5245 4749 5354 4552 2073 6970 3a31 3034
	0x0010:  2e31 3937 2e36 392e 3720 5349 502f 322e
	0x0020:  300d 0a56 6961 3a20 5349 502f 322e 302f
	0x0030:  5544 5020 3130 2e32 3030 2e31 3031 2e31
	0x0040:  3136 3a35 3837 3831 3b62 7261 6e63 683d
	0x0050:  7a39 6847 3462 4b2d 3532 3432 3837 2d31
	0x0060:  2d2d 2d35 3130 6337 3031 6565 6435 3266
	0x0070:  3336 343b 7270 6f72 740d 0a4d 6178 2d46
	0x0080:  6f72 7761 7264 733a 2037 300d 0a43 6f6e
	0x0090:  7461 6374 3a20 3c73 6970 3a31 3530 3640
	0x00a0:  3130 2e32 3030 2e31 3031 2e31 3136 3a35
	0x00b0:  3837 3831 3b72 696e 7374 616e 6365 3d31
	0x00c0:  6337 6362 3531 6439 6634 3634 6564 333e
	0x00d0:  0d0a 546f 3a20 3c73 6970 3a31 3530 3640
	0x00e0:  3130 342e 3139 372e 3639 2e37 3e0d 0a46
	0x00f0:  726f 6d3a 203c 7369 703a 3135 3036 4031
	0x0100:  3034 2e31 3937 2e36 392e 373e 3b74 6167
	0x0110:  3d62 6332 3031 3333 390d 0a43 616c 6c2d
	0x0120:  4944 3a20 3832 3136 324d 6a41 354e 5445
	0x0130:  3059 6a6b 795a 5445 344d 6a41 335a 4755
	0x0140:  7a4e 7a5a 6859 6a51 784e 4463 324d 546c
	0x0150:  6c4d 7a49 0d0a 4353 6571 3a20 3120 5245
	0x0160:  4749 5354 4552 0d0a 4578 7069 7265 733a
	0x0170:  2033 3630 300d 0a41 6c6c 6f77 3a20 5355
	0x0180:  4253 4352 4942 452c 204e 4f54 4946 592c
	0x0190:  2049 4e56 4954 452c 2041 434b 2c20 4341
	0x01a0:  4e43 454c 2c20 4259 452c 2052 4546 4552
	0x01b0:  2c20 494e 464f 2c20 4f50 5449 4f4e 532c
	0x01c0:  204d 4553 5341 4745 0d0a 5573 6572 2d41
	0x01d0:  6765 6e74 3a20 4272 6961 2034 2072 656c
	0x01e0:  6561 7365 2034 2e36 2e30 2073 7461 6d70
	0x01f0:  2038 3231 3632 0d0a 436f 6e74 656e 742d
	0x0200:  4c65 6e67 7468 3a20 300d 0a0d 0a
^C
6 packets captured
8 packets received by filter
0 packets dropped by kernel
{code}




--
This message was sent by Atlassian JIRA
(v6.2#6252)



More information about the asterisk-bugs mailing list