Hi all, Is any one have any idea why I am getting this error in stress testing. Initial registration is also failed:
*[]ubuntu@stress:~$ /usr/share/clearwater/bin/run_stress imsunil.org <http://imsunil.org> 50 2 --icscf-target 192.168.56.112:5052 <http://192.168.56.112:5052> --scscf-target 192.168.56.112:5054 <http://192.168.56.112:5054>* Starting initial registration, will take 0 seconds Initial registration failed - see /var/log/clearwater-sip-stress/26377_initial_reg_errors.log for details of the errors *$cat /var/log/clearwater-sip-stress/26377_initial_reg_errors.log* 2018-04-25 17:26:11.498523 1524657371.498523: Aborting call on unexpected message for Call-Id '[email protected]': while expecting '401' (index 1), received 'SIP/2.0 403 Forbidden Via: SIP/2.0/TCP 192.168.56.116:35760 ;received=192.168.56.116;branch=z9hG4bK-25046-48-0 Call-ID: [email protected] From: <sip:[email protected]>;tag=25046SIPpTag0048 To: <sip:[email protected] >;tag=z9hG4bKPjtI4ClsjDofR5gP4xyDBk9C3kW49Sbbch CSeq: 1 REGISTER P-Charging-Vector: icid-value="d4511351a7e24c5ff16243bac827fc3f48" Content-Length: 0 *Part of sprout log:* *cat sprout_current.txt* --start msg-- SIP/2.0 200 OK Via: SIP/2.0/TCP 192.168.56.112;rport=41096;received=192.168.56.112;branch=z9hG4bK-319664 Call-ID: poll-sip-319664 From: "poll-sip" <sip:[email protected]>;tag=319664 To: <sip:[email protected]>;tag=z9hG4bK-319664 CSeq: 319664 OPTIONS Content-Length: 0 --end msg-- 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug common_sip_processing.cpp:275: Skipping SAS logging for OPTIONS response 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug pjsip: tdta0x7f421c01 Destroying txdata Response msg 200/OPTIONS/cseq=319664 (tdta0x7f421c017980) 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug thread_dispatcher.cpp:273: Worker thread completed processing message 0x7f40fc051208 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug thread_dispatcher.cpp:287: Request latency = 269us 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug event_statistic_accumulator.cpp:32: Accumulate 269 for 0x21f53d8 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug event_statistic_accumulator.cpp:32: Accumulate 269 for 0x21f5450 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug load_monitor.cpp:341: Not recalculating rate as we haven't processed 20 requests yet (only 15). 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug utils.cpp:878: Removed IOHook 0x7f41f6fd4df0 to stack. There are now 0 hooks 25-04-2018 12:39:28.573 UTC [7f41f6fd5700] Debug thread_dispatcher.cpp:161: Attempting to process queue element 25-04-2018 12:39:28.573 UTC [7f4102ded700] Verbose httpstack.cpp:308: Process request for URL /ping, args (null) 25-04-2018 12:39:28.573 UTC [7f4102ded700] Verbose httpstack.cpp:68: Sending response 200 to request for URL /ping, args (null) 25-04-2018 12:39:30.574 UTC [7f41035ee700] Verbose pjsip: tcps0x7f40fc02 TCP connection closed 25-04-2018 12:39:30.574 UTC [7f41035ee700] Debug connection_tracker.cpp:67: Connection 0x7f40fc028928 has been destroyed 25-04-2018 12:39:30.574 UTC [7f41035ee700] Verbose pjsip: tcps0x7f40fc02 TCP transport destroyed with reason 70016: End of file (PJ_EEOF) 25-04-2018 12:39:30.585 UTC [7f41035ee700] Verbose pjsip: tcplis:5053 TCP listener 192.168.56.112:5053: got incoming TCP connection from 192.168.56.112:38808, sock=747 25-04-2018 12:39:30.585 UTC [7f41035ee700] Verbose pjsip: tcps0x7f40fc02 tcp->base.local_name: 192.168.56.112 25-04-2018 12:39:30.585 UTC [7f41035ee700] Verbose pjsip: tcps0x7f40fc02 TCP server transport created 25-04-2018 12:39:30.585 UTC [7f41035ee700] Debug pjsip: sip_endpoint.c Processing incoming message: Request msg OPTIONS/cseq=319666 (rdata0x7f40fc028c60) 25-04-2018 12:39:30.585 UTC [7f41035ee700] Verbose common_sip_processing.cpp:87: RX 361 bytes Request msg OPTIONS/cseq=319666 (rdata0x7f40fc028c60) from TCP 192.168.56.112:38808: --start msg-- OPTIONS sip:[email protected]:5053 SIP/2.0 Via: SIP/2.0/TCP 192.168.56.112;rport;branch=z9hG4bK-319666 Max-Forwards: 2 To: <sip:[email protected]:5053> From: poll-sip <sip:[email protected]>;tag=319666 Call-ID: poll-sip-319666 CSeq: 319666 OPTIONS Contact: <sip:192.168.56.112> Accept: application/sdp Content-Length: 0 User-Agent: poll-sip --end msg-- 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug uri_classifier.cpp:139: home domain: false, local_to_node: true, is_gruu: false, enforce_user_phone: false, prefer_sip: true, treat_number_as_phone: false 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug uri_classifier.cpp:173: Classified URI sip:[email protected]:5053 as 3 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug common_sip_processing.cpp:180: Skipping SAS logging for OPTIONS request 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug thread_dispatcher.cpp:568: Received message 0x7f40fc028c60 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug thread_dispatcher.cpp:585: Admitted request 0x7f40fc028c60 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug thread_dispatcher.cpp:620: Incoming message 0x7f40fc028c60 cloned to 0x7f40fc051208 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug thread_dispatcher.cpp:639: Queuing cloned received message 0x7f40fc051208 for worker threads with priority 15 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug event_statistic_accumulator.cpp:32: Accumulate 0 for 0x21f9338 25-04-2018 12:39:30.586 UTC [7f41035ee700] Debug event_statistic_accumulator.cpp:32: Accumulate 0 for 0x21f93e0 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug utils.cpp:872: Added IOHook 0x7f41f7fd6df0 to stack. There are now 1 hooks 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug thread_dispatcher.cpp:181: Worker thread dequeue message 0x7f40fc051208 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug thread_dispatcher.cpp:186: Request latency so far = 140us 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug pjsip: sip_endpoint.c Distributing rdata to modules: Request msg OPTIONS/cseq=319666 (rdata0x7f40fc051208) 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug uri_classifier.cpp:139: home domain: false, local_to_node: true, is_gruu: false, enforce_user_phone: false, prefer_sip: true, treat_number_as_phone: false 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug uri_classifier.cpp:173: Classified URI sip:[email protected]:5053 as 3 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug pjsip: endpoint Response msg 200/OPTIONS/cseq=319666 (tdta0x322f910) created 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Verbose common_sip_processing.cpp:103: TX 290 bytes Response msg 200/OPTIONS/cseq=319666 (tdta0x322f910) to TCP 192.168.56.112:38808: --start msg-- SIP/2.0 200 OK Via: SIP/2.0/TCP 192.168.56.112;rport=38808;received=192.168.56.112;branch=z9hG4bK-319666 Call-ID: poll-sip-319666 From: "poll-sip" <sip:[email protected]>;tag=319666 To: <sip:[email protected]>;tag=z9hG4bK-319666 CSeq: 319666 OPTIONS Content-Length: 0 --end msg-- 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug common_sip_processing.cpp:275: Skipping SAS logging for OPTIONS response 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug pjsip: tdta0x322f910 Destroying txdata Response msg 200/OPTIONS/cseq=319666 (tdta0x322f910) 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug thread_dispatcher.cpp:273: Worker thread completed processing message 0x7f40fc051208 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug thread_dispatcher.cpp:287: Request latency = 432us 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug event_statistic_accumulator.cpp:32: Accumulate 432 for 0x21f53a8 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug event_statistic_accumulator.cpp:32: Accumulate 432 for 0x21f5450 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug load_monitor.cpp:341: Not recalculating rate as we haven't processed 20 requests yet (only 16). 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug utils.cpp:878: Removed IOHook 0x7f41f7fd6df0 to stack. There are now 0 hooks 25-04-2018 12:39:30.586 UTC [7f41f7fd7700] Debug thread_dispatcher.cpp:161: Attempting to process queue element thanks, sunil
_______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/mailman/listinfo/clearwater_lists.projectclearwater.org
