[asterisk-bugs] [JIRA] (ASTERISK-26583) When using PJSIP transports in MariaDB table via ODBC; traffic from Asterisk is not seen on the network.

Joshua Colp (JIRA) noreply at issues.asterisk.org
Tue Nov 15 05:38:10 CST 2016


     [ https://issues.asterisk.org/jira/browse/ASTERISK-26583?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Joshua Colp updated ASTERISK-26583:
-----------------------------------

    Component/s:     (was: pjproject/pjsip)
                     (was: Resources/res_config_odbc)
                 Resources/res_pjsip
                 Core/Sorcery

> When using PJSIP transports in MariaDB table via ODBC; traffic from Asterisk is not seen on the network.
> --------------------------------------------------------------------------------------------------------
>
>                 Key: ASTERISK-26583
>                 URL: https://issues.asterisk.org/jira/browse/ASTERISK-26583
>             Project: Asterisk
>          Issue Type: Bug
>      Security Level: None
>          Components: Core/Sorcery, Resources/res_pjsip
>    Affects Versions: 13.9.1, 13.12.0, 13.11.1, 13.12.2, 14.1.1
>         Environment: Centos 7.2.1511
> kernel 3.10.0-327.36.3.el7
>            Reporter: Jeff Clay
>            Assignee: Unassigned
>
> 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