[Sep 7 15:48:52] DEBUG[26796] res_pjsip/pjsip_transport_events.c: Reliable transport 'tlss0x7f8a1804e3a8' state:CONNECTED [Sep 7 15:48:52] VERBOSE[26796] res_pjsip_logger.c: <--- Received SIP request (705 bytes) from TLS:10.0.0.132:1180 ---> REGISTER sip:sip0.srv.local:5061;transport=tls SIP/2.0 Via: SIP/2.0/TLS 192.168.1.6:53329;rport;branch=z9hG4bKPjEI4N4XIKdHJvA4hbv-FJrV11ij7nowob;alias Max-Forwards: 70 From: "1003316" ;tag=0qetjUnmO41t6EelKEZ7djunLTyCbgSu To: "1003316" Call-ID: ylM7K0aZIt.IhkXVF-vE2ZuhbY0CHwPX CSeq: 42314 REGISTER User-Agent: sip client Supported: outbound, path Contact: "1003316" ;reg-id=1;+sip.instance="" Expires: 300 Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Content-Length: 0 [Sep 7 15:48:52] DEBUG[26796] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=42314 (rdata0x7f8a1804e698) [Sep 7 15:48:52] DEBUG[26796] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000052 to use for Request msg REGISTER/cseq=42314 (rdata0x7f8a1804e698) [Sep 7 15:48:52] DEBUG[26794] threadpool.c: Increasing threadpool pjsip/pool's size by 5 [Sep 7 15:48:52] DEBUG[27428] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:48:52] DEBUG[27428] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:48:52] DEBUG[27428] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 7 15:48:52] DEBUG[27428] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316@sip0.srv.local' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [90] in [0, 4294967295] gives [90](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [15] in [0, 4294967295] gives [15](0) [Sep 7 15:48:52] DEBUG[27428] res_pjsip_endpoint_identifier_user.c: Identified by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [32] in [0, 4294967295] gives [32](0) [Sep 7 15:48:52] DEBUG[27428] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1003316'. [Sep 7 15:48:52] DEBUG[27428] netsock2.c: Splitting '172.31.26.174' into... [Sep 7 15:48:52] DEBUG[27428] netsock2.c: ...host '172.31.26.174' and port ''. [Sep 7 15:48:52] DEBUG[27428] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:48:52] DEBUG[27428] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:48:52] DEBUG[27391] manager.c: Examining AMI event: Event: ChallengeSent Privilege: security,all EventTV: 2020-09-07T15:48:52.898+0900 Severity: Informational Service: PJSIP EventVersion: 1 AccountID: 1003316 SessionID: ylM7K0aZIt.IhkXVF-vE2ZuhbY0CHwPX LocalAddress: IPV4/TLS/172.31.26.174/5061 RemoteAddress: IPV4/TLS/10.0.0.132/1180 Challenge: [Sep 7 15:48:52] VERBOSE[27428] res_pjsip_logger.c: <--- Transmitting SIP response (583 bytes) to TLS:10.0.0.132:1180 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/TLS 192.168.1.6:53329;rport=1180;received=10.0.0.132;branch=z9hG4bKPjEI4N4XIKdHJvA4hbv-FJrV11ij7nowob;alias Call-ID: ylM7K0aZIt.IhkXVF-vE2ZuhbY0CHwPX From: "1003316" ;tag=0qetjUnmO41t6EelKEZ7djunLTyCbgSu To: "1003316" ;tag=z9hG4bKPjEI4N4XIKdHJvA4hbv-FJrV11ij7nowob CSeq: 42314 REGISTER WWW-Authenticate: Digest realm="asterisk",nonce="1599461332/b12b9121d522871363996e0264632095",opaque="0c8354c44def99c9",algorithm=md5,qop="auth" Server: Asterisk PBX Content-Length: 0 [Sep 7 15:48:52] VERBOSE[26796] res_pjsip_logger.c: <--- Received SIP request (1009 bytes) from TLS:10.0.0.132:1180 ---> REGISTER sip:sip0.srv.local:5061;transport=tls SIP/2.0 Via: SIP/2.0/TLS 10.0.0.132:1180;rport;branch=z9hG4bKPjP1tU0sgN5e1MRMghWQu9saL1hdDNQqvv;alias Max-Forwards: 70 From: "1003316" ;tag=0qetjUnmO41t6EelKEZ7djunLTyCbgSu To: "1003316" Call-ID: ylM7K0aZIt.IhkXVF-vE2ZuhbY0CHwPX CSeq: 42315 REGISTER User-Agent: sip client Supported: outbound, path Contact: ;reg-id=1;+sip.instance="" Expires: 300 Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Authorization: Digest username="1003316", realm="asterisk", nonce="1599461332/b12b9121d522871363996e0264632095", uri="sip:sip0.srv.local:5061;transport=tls", response="f611e8e39d0b408f1ff9b0ad50e3348d", algorithm=md5, cnonce="HKqD.pyujRjPi8XHOtgM53JPIoYEz00F", opaque="0c8354c44def99c9", qop=auth, nc=00000001 Content-Length: 0 [Sep 7 15:48:52] DEBUG[26796] res_pjsip/pjsip_distributor.c: Could not find matching transaction for Request msg REGISTER/cseq=42315 (rdata0x7f8a1804e698) [Sep 7 15:48:52] DEBUG[26796] res_pjsip/pjsip_distributor.c: Calculated serializer pjsip/distributor-00000052 to use for Request msg REGISTER/cseq=42315 (rdata0x7f8a1804e698) [Sep 7 15:48:52] DEBUG[27428] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:48:52] DEBUG[27428] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:48:52] DEBUG[27428] res_pjsip_endpoint_identifier_ip.c: No identify sections to match against [Sep 7 15:48:52] DEBUG[27428] res_pjsip_endpoint_identifier_user.c: Attempting identify by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316@sip0.srv.local' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [90] in [0, 4294967295] gives [90](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [15] in [0, 4294967295] gives [15](0) [Sep 7 15:48:52] DEBUG[27428] res_pjsip_endpoint_identifier_user.c: Identified by From username '1003316' domain 'sip0.srv.local' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [32] in [0, 4294967295] gives [32](0) [Sep 7 15:48:52] DEBUG[27428] res_pjsip_authenticator_digest.c: Using default realm 'asterisk' on incoming auth '1003316'. [Sep 7 15:48:52] DEBUG[27428] res_pjsip_authenticator_digest.c: Calculated nonce 1599461332/b12b9121d522871363996e0264632095. Actual nonce is 1599461332/b12b9121d522871363996e0264632095 [Sep 7 15:48:52] DEBUG[27428] netsock2.c: Splitting '172.31.26.174' into... [Sep 7 15:48:52] DEBUG[27428] netsock2.c: ...host '172.31.26.174' and port ''. [Sep 7 15:48:52] DEBUG[27428] netsock2.c: Splitting '10.0.0.132' into... [Sep 7 15:48:52] DEBUG[27428] netsock2.c: ...host '10.0.0.132' and port ''. [Sep 7 15:48:52] DEBUG[27391] manager.c: Examining AMI event: Event: SuccessfulAuth Privilege: security,all EventTV: 2020-09-07T15:48:52.920+0900 Severity: Informational Service: PJSIP EventVersion: 1 AccountID: 1003316 SessionID: ylM7K0aZIt.IhkXVF-vE2ZuhbY0CHwPX LocalAddress: IPV4/TLS/172.31.26.174/5061 RemoteAddress: IPV4/TLS/10.0.0.132/1180 UsingPassword: 1 [Sep 7 15:48:52] DEBUG[27428] res_pjsip_registrar.c: Matched id '1003316' to aor '1003316' [Sep 7 15:48:52] DEBUG[27428] res_pjsip_registrar.c: Matched aor '1003316' by To username [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [60] in [0, 4294967295] gives [60](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_contacts WHERE id LIKE ? ORDER BY id [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id LIKE') = '1003316;@%' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[27428] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: INSERT INTO ps_contacts (id, via_addr, qualify_timeout, call_id, reg_server, prune_on_boot, path, endpoint, via_port, authenticate_qualify, uri, qualify_frequency, user_agent, expiration_time, outbound_proxy) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316;@d46d1ff6c4d83c2c654e05df07e3aff2' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 2 ('via_addr') = '10.0.0.132' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 3 ('qualify_timeout') = '3.000000' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 4 ('call_id') = 'ylM7K0aZIt.IhkXVF-vE2ZuhbY0CHwPX' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 5 ('reg_server') = '' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 6 ('prune_on_boot') = 'no' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 7 ('path') = '' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 8 ('endpoint') = '1003316' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 9 ('via_port') = '1180' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 10 ('authenticate_qualify') = 'no' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 11 ('uri') = 'sip:1003316@10.0.0.132:1180;transport=TLS;ob' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 12 ('qualify_frequency') = '0' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 13 ('user_agent') = 'sip client' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 14 ('expiration_time') = '1599461632' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 15 ('outbound_proxy') = '' [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[26769] threadpool.c: Increasing threadpool sorcery/pool's size by 1 [Sep 7 15:48:52] DEBUG[27430] res_pjsip/pjsip_options.c: An unqualified contact has been added to AOR '1003316' so it is now available [Sep 7 15:48:52] DEBUG[27430] res_pjsip/pjsip_options.c: Endpoint state compositor '1003316' is online as AOR '1003316' is available [Sep 7 15:48:52] DEBUG[26779] devicestate.c: No provider found, checking channel drivers for PJSIP - 1003316 [Sep 7 15:48:52] DEBUG[27391] manager.c: Examining AMI event: Event: PeerStatus Privilege: system,all ChannelType: PJSIP Peer: PJSIP/1003316 PeerStatus: Reachable [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_aors WHERE id = ? [Sep 7 15:48:52] DEBUG[27428] res_config_odbc.c: Parameter 1 ('id') = '1003316' [Sep 7 15:48:52] DEBUG[27391] manager.c: Examining AMI event: Event: ContactStatus Privilege: system,all URI: sip:1003316@10.0.0.132:1180;transport=TLS;ob ContactStatus: NonQualified AOR: 1003316 EndpointName: 1003316 RoundtripUsec: 0 [Sep 7 15:48:52] DEBUG[27428] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[26779] res_odbc.c: Reusing ODBC handle 0x3000fe0 from class 'asterisk' [Sep 7 15:48:52] DEBUG[26779] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ? [Sep 7 15:48:52] DEBUG[26779] res_config_odbc.c: Parameter 1 ('id') = '1003316' [Sep 7 15:48:52] DEBUG[27428] config.c: extract double from [3.0] in [-inf, inf] gives [3.000000](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [7200] in [0, 4294967295] gives [7200](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [3600] in [0, 4294967295] gives [3600](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [60] in [0, 4294967295] gives [60](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[27428] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] VERBOSE[27428] res_pjsip_logger.c: <--- Transmitting SIP response (551 bytes) to TLS:10.0.0.132:1180 ---> SIP/2.0 200 OK Via: SIP/2.0/TLS 10.0.0.132:1180;rport=1180;received=10.0.0.132;branch=z9hG4bKPjP1tU0sgN5e1MRMghWQu9saL1hdDNQqvv;alias Call-ID: ylM7K0aZIt.IhkXVF-vE2ZuhbY0CHwPX From: "1003316" ;tag=0qetjUnmO41t6EelKEZ7djunLTyCbgSu To: "1003316" ;tag=z9hG4bKPjP1tU0sgN5e1MRMghWQu9saL1hdDNQqvv CSeq: 42315 REGISTER Date: Mon, 07 Sep 2020 06:48:52 GMT Contact: ;expires=299 Expires: 300 Server: Asterisk PBX Content-Length: 0 [Sep 7 15:48:52] DEBUG[26779] res_odbc.c: Releasing ODBC handle 0x3000fe0 into pool [Sep 7 15:48:52] DEBUG[26779] res_sorcery_realtime.c: Filtering out realtime field 'disallow' from retrieval [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [1800] in [0, 4294967295] gives [1800](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [90] in [0, 4294967295] gives [90](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [0] in [0, 4294967295] gives [0](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [1] in [0, 4294967295] gives [1](0) [Sep 7 15:48:52] DEBUG[26779] config.c: extract uint from [15] in [0, 4294967295] gives [15](0) [Sep 7 15:48:52] DEBUG[26779] devicestate.c: Changing state for PJSIP/1003316 - state 1 (Not in use) [Sep 7 15:48:52] DEBUG[27391] manager.c: Examining AMI event: Event: DeviceStateChange Privilege: call,all Device: PJSIP/1003316 State: NOT_INUSE