[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63:41862' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port '41862'.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6:8089' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port '8089'.
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_transport_websocket.c:448 websocket_on_rx_msg: Request msg INVITE/cseq=1 (rdata0x7f8148017e28) re-writing Contact URI from 4ttrkmlt4abl.invalid:0;transport=ws to 10.5.83.63:41862;transport=ws
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip/pjsip_distributor.c:393 find_dialog: Could not find matching transaction for Request msg INVITE/cseq=1 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002c to use for Request msg INVITE/cseq=1 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port ''.
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id LIKE') = '%'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.20.32'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.83.63'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1@my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_sorcery_realtime.c:132 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [30] in [0, 4294967295] gives [30](0)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [32] in [0, 4294967295] gives [32](0)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_authenticator_digest.c:471 digest_check_auth: Using default realm 'bizfly-call-center-core' on incoming auth '02473000630_1'.
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_authenticator_digest.c:358 verify: Realm: bizfly-call-center-core  Username: 02473000630_1  Result: NOAUTH
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port ''.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port ''.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6:8089' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port '8089'.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63:41862' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port '41862'.
[2024-03-27 11:29:38] DEBUG[3385814]: res_http_websocket.c:381 __ast_websocket_write: Writing websocket text frame, length 534
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63:41862' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port '41862'.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6:8089' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port '8089'.
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip/pjsip_distributor.c:393 find_dialog: Could not find matching transaction for Request msg ACK/cseq=1 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002c to use for Request msg ACK/cseq=1 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port ''.
[2024-03-27 11:29:38] DEBUG[3385815]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63:41862' into...
[2024-03-27 11:29:38] DEBUG[3385815]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port '41862'.
[2024-03-27 11:29:38] DEBUG[3385815]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6:8089' into...
[2024-03-27 11:29:38] DEBUG[3385815]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port '8089'.
[2024-03-27 11:29:38] DEBUG[3385815]: res_pjsip_transport_websocket.c:448 websocket_on_rx_msg: Request msg INVITE/cseq=2 (rdata0x7f8148017e28) re-writing Contact URI from 4ttrkmlt4abl.invalid:0;transport=ws to 10.5.83.63:41862;transport=ws
[2024-03-27 11:29:38] DEBUG[3385815]: res_pjsip/pjsip_distributor.c:393 find_dialog: Could not find matching transaction for Request msg INVITE/cseq=2 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385815]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002c to use for Request msg INVITE/cseq=2 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id LIKE') = '%'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.20.32'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.83.63'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1@my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_sorcery_realtime.c:132 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [30] in [0, 4294967295] gives [30](0)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port ''.
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id LIKE') = '%'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.20.32'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.83.63'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1@my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_sorcery_realtime.c:132 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [30] in [0, 4294967295] gives [30](0)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_auths WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [32] in [0, 4294967295] gives [32](0)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_authenticator_digest.c:471 digest_check_auth: Using default realm 'bizfly-call-center-core' on incoming auth '02473000630_1'.
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_authenticator_digest.c:261 check_nonce: Calculated nonce 1711513778/1f291806f87bb4ec1193047b9476ceca. Actual nonce is 1711513778/1f291806f87bb4ec1193047b9476ceca
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_authenticator_digest.c:358 verify: Realm: bizfly-call-center-core  Username: 02473000630_1  Result: SUCCESS
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port ''.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port ''.
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4235 session_on_rx_request:  (null session) Request: INVITE
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4062 handle_new_invite_request:  Request:
[2024-03-27 11:29:38] DEBUG[3385814]: dsp.c:510 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[2024-03-27 11:29:38] DEBUG[3385814]: dsp.c:510 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002c to use for Request msg INVITE/cseq=2 (rdata0x7f8154049e28)
[2024-03-27 11:29:38] DEBUG[3385814]: chan_pjsip.c:2954 chan_pjsip_session_begin:  02473000630_1
[2024-03-27 11:29:38] DEBUG[3385814]: chan_pjsip.c:2958 chan_pjsip_session_begin:  Direct media no glare mitigation
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:3916 new_invite:  02473000630_1
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:3948 new_invite:  02473000630_1: Call (WSS:10.5.83.63:41862) to extension '1' - partial match
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4514 handle_outgoing_response:  02473000630_1: Method is INVITE, Response is 484 Address Incomplete
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4533 handle_outgoing_response:  02473000630_1
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6:8089' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port '8089'.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63:41862' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port '41862'.
[2024-03-27 11:29:38] DEBUG[3385814]: res_http_websocket.c:381 __ast_websocket_write: Writing websocket text frame, length 401
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4630 session_inv_on_state_changed:  02473000630_1 Event: TSX_STATE  Inv State: DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint '02473000630_1()'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f81200629a8)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4402 __print_debug_details: There is no transaction involved in this state change
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4656 session_inv_on_state_changed: 02473000630_1: Source of transaction state change is TX_MSG
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4704 session_inv_on_state_changed:
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  02473000630_1 TSX State: Completed  Inv State: DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4382 __print_debug_details: The state change pertains to the endpoint '02473000630_1()'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f81200629a8)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4393 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f81200629a8
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Completed
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is TX_MSG
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  Disconnected
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Completed  Inv State: DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4189 session_on_tsx_state:
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4045 new_invite:  02473000630_1
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4136 handle_new_invite_request:  Request:  Session: 02473000630_1
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4254 session_on_rx_request:  (null session) Handled request INVITE  ? yes
[2024-03-27 11:29:38] DEBUG[3385814]: chan_pjsip.c:2975 chan_pjsip_session_end:  02473000630_1
[2024-03-27 11:29:38] DEBUG[3385814]: chan_pjsip.c:2978 chan_pjsip_session_end:  No channel
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:2912 session_destructor: 02473000630_1: Destroying SIP session
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63:41862' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port '41862'.
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.82.6:8089' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.82.6' and port '8089'.
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7f8120029618 for Request msg ACK/cseq=2 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip/pjsip_distributor.c:471 ast_sip_get_distributor_serializer: Calculated serializer pjsip/distributor-0000002c to use for Request msg ACK/cseq=2 (rdata0x7f8148017e28)
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '10.5.83.63' into...
[2024-03-27 11:29:38] DEBUG[3385814]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '10.5.83.63' and port ''.
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoint_id_ips WHERE id LIKE ? ORDER BY id
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id LIKE') = '%'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.20.32'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_ip.c:258 ip_identify_match_check: Source address 10.5.83.63:41862 does not match identify '10.5.83.63'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:148 username_identify: Attempting identify by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1@my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_domain_aliases WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:965 _ast_odbc_request_obj2: Reusing ODBC handle 0x5619e828f080 from class 'mysql'
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:115 custom_prepare: Skip: 0; SQL: SELECT * FROM ps_endpoints WHERE id = ?
[2024-03-27 11:29:38] DEBUG[3385814]: res_config_odbc.c:134 custom_prepare: Parameter 1 ('id') = '02473000630_1'
[2024-03-27 11:29:38] DEBUG[3385814]: res_odbc.c:808 ast_odbc_release_obj: Releasing ODBC handle 0x5619e828f080 into pool
[2024-03-27 11:29:38] DEBUG[3385814]: res_sorcery_realtime.c:132 sorcery_realtime_filter_objectset: Filtering out realtime field 'disallow' from retrieval
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1800] in [0, 4294967295] gives [1800](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [1] in [0, 4294967295] gives [1](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [90] in [0, 4294967295] gives [90](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [0] in [0, 4294967295] gives [0](0)
[2024-03-27 11:29:38] DEBUG[3385814]: config.c:3753 ast_parse_arg: extract uint from [30] in [0, 4294967295] gives [30](0)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_endpoint_identifier_user.c:160 username_identify: Identified by From username '02473000630_1' domain 'my.domain.com'
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4380 __print_debug_details: inv_session 0x7f8148071b98 has no ast session
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4387 __print_debug_details: The inv session still has an invite_tsx (0x7f81200629a8)
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4393 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f81200629a8
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Confirmed
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is RX_MSG
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4760 session_inv_on_tsx_state_changed:  Session ended
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
[2024-03-27 11:29:38] DEBUG[3385814]: res_pjsip_session.c:4189 session_on_tsx_state:
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4748 session_inv_on_tsx_state_changed:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4368 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4380 __print_debug_details: inv_session 0x7f8148071b98 has no ast session
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4390 __print_debug_details: The inv session does NOT have an invite_tsx
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4393 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7f81200629a8
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4397 __print_debug_details: The current transaction state is Terminated
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4399 __print_debug_details: The transaction state change event is TIMER
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4404 __print_debug_details: The current inv state is DISCONNCTD
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4760 session_inv_on_tsx_state_changed:  Session ended
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4180 session_on_tsx_state:  (null session) TSX State: Terminated  Inv State: DISCONNCTD
[2024-03-27 11:29:38] DEBUG[4009504]: res_pjsip_session.c:4189 session_on_tsx_state: