PPS. The diameter answer si DIAMETER_UNREGISTERED_SERVICE when the user is not registered.
2014-10-15 15:40 GMT+02:00 Ruggero Schiavi <[email protected]>: > Hi, > Sorry to bother you again, I am integrating the HSS and I followed the > guide lines of your website. > To have it working I added my domain zone inside the visited network of > the public Identities and sprout:5054 as s-cscf. > > I am getting troubles if I call/perform a subscribe to a not-registered > user: sprout in fact answers with a busy everywhere, I modified the IFCs to > try to have them as similar as possible when they were provided by > Homestead. > > Please let me know if you have any suggestion about configuration or the > problem, > > I thank for any help you may provide, > > Abaco > > Ps. since this is still in testing OpenHss is mounted on the Homer machine > > > > > > > 15-10-2014 13:05:03.679 UTC Debug httpconnection.cpp:833: Received header > http/1.1200ok with value > 15-10-2014 13:05:03.679 UTC Debug httpconnection.cpp:833: Received header > content-length with value 938 > 15-10-2014 13:05:03.679 UTC Debug httpconnection.cpp:833: Received header > content-type with value text/plain > 15-10-2014 13:05:03.679 UTC Debug httpconnection.cpp:833: Received header > with value > 15-10-2014 13:05:03.679 UTC Debug httpconnection.cpp:553: Received HTTP > response : <ClearwaterRegData> > <RegistrationState>REGISTERED</RegistrationState> > <IMSSubscription> > <PrivateID>[email protected]</PrivateID> > <ServiceProfile> > <PublicIdentity> > <Identity>sip:[email protected]</Identity> > <Extension> > <IdentityType>0</IdentityType> > </Extension> > </PublicIdentity> > <InitialFilterCriteria> > <Priority>0</Priority> > <TriggerPoint> > <ConditionTypeCNF>0</ConditionTypeCNF> > <SPT> > <ConditionNegated>0</ConditionNegated> > <Group>0</Group> > <Method>INVITE</Method> > <Extension> > <RegistrationType>1</RegistrationType> > <RegistrationType>2</RegistrationType> > </Extension> > </SPT> > </TriggerPoint> > <ApplicationServer> > <ServerName>sip:mmtel.ims.prod.lan</ServerName> > <DefaultHandling>0</DefaultHandling> > </ApplicationServer> > </InitialFilterCriteria> > </ServiceProfile> > </IMSSubscription> > </ClearwaterRegData> > > > 15-10-2014 13:05:03.679 UTC Debug hssconnection.cpp:331: Processing > Identity node from HSS XML - sip:[email protected] > > 15-10-2014 13:05:03.679 UTC Debug scscfsproutlet.cpp:708: Successfully > looked up iFCs > 15-10-2014 13:05:03.679 UTC Debug acr.cpp:1495: Create RalfACR for node > type S-CSCF with role Originating > 15-10-2014 13:05:03.679 UTC Debug acr.cpp:48: Created ACR (0x7f77f0540560) > 15-10-2014 13:05:03.679 UTC Debug acr.cpp:195: Created S-CSCF Ralf ACR > 15-10-2014 13:05:03.679 UTC Debug aschain.cpp:75: Creating AsChain > 0x7f77f0540770 with 1 IFC and adding to map > 15-10-2014 13:05:03.679 UTC Debug aschain.cpp:77: Attached ACR > (0x7f77f0540560) to chain > 15-10-2014 13:05:03.679 UTC Debug scscfsproutlet.cpp:828: S-CSCF sproutlet > transaction 0x7f77f01d3030 linked to AsChain > AsChain-orig[0x7f77f0540770]:1/1 > 15-10-2014 13:05:03.679 UTC Debug scscfsproutlet.cpp:729: Single > Record-Route - initiation of originating handling > 15-10-2014 13:05:03.679 UTC Debug sproutletproxy.cpp:324: Creating URI for > scscf > 15-10-2014 13:05:03.679 UTC Debug sproutletproxy.cpp:328: Add services > parameter > 15-10-2014 13:05:03.679 UTC Debug sproutletproxy.cpp:336: > sip:ppsprout1.ims.prod.lan:5054;transport=TCP;lr;service=scscf > 15-10-2014 13:05:03.679 UTC Debug acr.cpp:234: Set record type for P/S-CSCF > 15-10-2014 13:05:03.679 UTC Debug acr.cpp:261: Dialog-initiating INVITE => > START_RECORD > 15-10-2014 13:05:03.680 UTC Debug acr.cpp:1229: Stored 0 subscription > identifiers > 15-10-2014 13:05:03.680 UTC Debug acr.cpp:1307: Found P-Charging-Vector > header, store information > 15-10-2014 13:05:03.680 UTC Info scscfsproutlet.cpp:394: Found served > user, so apply services > 15-10-2014 13:05:03.680 UTC Debug scscfsproutlet.cpp:836: Performing > originating initiating request processing > 15-10-2014 13:05:03.680 UTC Debug ifchandler.cpp:434: SPT class Method: > result true > 15-10-2014 13:05:03.680 UTC Debug ifchandler.cpp:538: Add to group 0 val > true > 15-10-2014 13:05:03.680 UTC Debug ifchandler.cpp:556: Result group 0 val > true > 15-10-2014 13:05:03.680 UTC Debug ifchandler.cpp:562: iFC matches > 15-10-2014 13:05:03.680 UTC Debug aschain.cpp:212: Matched iFC > AsChain-orig[0x7f77f0540770]:1/1 > 15-10-2014 13:05:03.680 UTC Info ifchandler.cpp:681: Found (triggered) > server sip:mmtel.ims.prod.lan > 15-10-2014 13:05:03.680 UTC Info scscfsproutlet.cpp:945: Routing to > Application Server sip:mmtel.ims.prod.lan with ODI token odi_Z6sxALGUH+ for > AsChain-orig[0x7f77f0540770]:1/1 > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:1137: Sproutlet > send_request 0x7f77f0601b30 > 15-10-2014 13:05:03.680 UTC Verbose sproutletproxy.cpp:1162: > scscf-0x7f77f01d3030 sending Request msg INVITE/cseq=1789968436 > (tdta0x7f77f0601520) on fork 0 > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:413: Started > Sproutlet timer, id = 140153105413808, duration = 2.000 > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 0 responses, 1 requests > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:1513: Processing > request 0x7f77f06015c8, fork = 0 > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:1631: > scscf-0x7f77f01d3030 transmitting request on fork 0 > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:1645: > scscf-0x7f77f01d3030 store reference to non-ACK request Request msg > INVITE/cseq=1789968436 (tdta0x7f77f0601520) on fork 0 > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f0601b30 => txdata 0x7f77f06015c8 mapping > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:110: Find target > Sproutlet for request > 15-10-2014 13:05:03.680 UTC Debug sproutletproxy.cpp:144: Found next > routable URI: sip:mmtel.ims.prod.lan;lr > 15-10-2014 13:05:03.680 UTC Debug mmtel.cpp:86: Found P-Served-User > header: P-Served-User: <sip:[email protected] > >;sescase=orig;regstate=reg > 15-10-2014 13:05:03.680 UTC Debug mmtel.cpp:149: Fetching simservs > configuration for sip:[email protected] > 15-10-2014 13:05:03.680 UTC Debug httpresolver.cpp:70: > HttpResolver::resolve for host pphomer1.ims.prod.lan, port 7888, family 2 > 15-10-2014 13:05:03.680 UTC Debug baseresolver.cpp:511: Attempt to parse > pphomer1.ims.prod.lan as IP address > 15-10-2014 13:05:03.680 UTC Debug dnscachedresolver.cpp:179: Pulling 1 > records from cache for pphomer1.ims.prod.lan A > 15-10-2014 13:05:03.680 UTC Debug baseresolver.cpp:359: Found 1 A/AAAA > records, randomizing > 15-10-2014 13:05:03.680 UTC Debug baseresolver.cpp:501: 10.99.99.165:7888 > transport 6 is not blacklisted > 15-10-2014 13:05:03.680 UTC Debug baseresolver.cpp:380: Added a server, > now have 1 of 5 > 15-10-2014 13:05:03.681 UTC Debug baseresolver.cpp:418: Adding 0 servers > from blacklist > 15-10-2014 13:05:03.681 UTC Debug httpconnection.cpp:540: Sending HTTP > request : > http://pphomer1.ims.prod.lan:7888/org.etsi.ngn.simservs/users/sip%3A87100000%40ims.prod.lan/simservs.xml > (trying 10.99.99.165) on new connection > 15-10-2014 13:05:03.689 UTC Error httpconnection.cpp:569: > http://pphomer1.ims.prod.lan:7888/org.etsi.ngn.simservs/users/sip%3A87100000%40ims.prod.lan/simservs.xml > failed at server 10.99.99.165 : HTTP response code said error (22 404) : > fatal > 15-10-2014 13:05:03.689 UTC Error httpconnection.cpp:667: cURL failure > with cURL error code 22 (see man 3 libcurl-errors) and HTTP error code 404 > 15-10-2014 13:05:03.689 UTC Debug statistic.cpp:103: Send new value for > statistic xdm_latency_us, size 5 > 15-10-2014 13:05:03.689 UTC Debug zmq_lvc.cpp:167: Update to > xdm_latency_us statistic > 15-10-2014 13:05:03.689 UTC Debug zmq_lvc.cpp:250: Clearing message cache > for 0x7f78280257f0 > 15-10-2014 13:05:03.690 UTC Debug mmtel.cpp:153: Failed to fetch simservs > configuration for sip:[email protected], no MMTel services enabled > 15-10-2014 13:05:03.690 UTC Verbose sproutletproxy.cpp:977: Created > Sproutlet mmtel-0x7f77f0253110 for Request msg INVITE/cseq=1789968436 > (tdta0x7f77f0601520) > 15-10-2014 13:05:03.690 UTC Info pjutils.cpp:1551: Cloning header! > 140153110670272 > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f0614b30 => txdata 0x7f77f06145c8 mapping > 15-10-2014 13:05:03.690 UTC Verbose sproutletproxy.cpp:1366: > scscf-0x7f77f01d3030 received provisional response Response msg > 100/INVITE/cseq=1789968436 (tdta0x7f77f0614520) on fork 0, state = > Proceeding > 15-10-2014 13:05:03.690 UTC Info scscfsproutlet.cpp:448: S-CSCF received > response > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:425: Cancelled > Sproutlet timer, id = 140153105413808 > 15-10-2014 13:05:03.690 UTC Verbose sproutletproxy.cpp:1189: > scscf-0x7f77f01d3030 sending Response msg 100/INVITE/cseq=1789968436 > (tdta0x7f77f0614520) > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 1 responses, 0 requests > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:1553: Aggregating > response with status code 100 > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:1570: Discard > 100/INVITE response (tdta0x7f77f0614520) > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f0614b30 => txdata 0x7f77f06145c8 mapping > 15-10-2014 13:05:03.690 UTC Debug pjsip: tdta0x7f77f061 Destroying txdata > Response msg 100/INVITE/cseq=1789968436 (tdta0x7f77f0614520) > 15-10-2014 13:05:03.690 UTC Debug pjutils.cpp:686: Cloned > tdta0x7f77f0601520 to tdta0x7f77f0614520 > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:1038: Remove top > Route header Route: <sip:mmtel.ims.prod.lan;lr> > 15-10-2014 13:05:03.690 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f0614b30 => txdata 0x7f77f06145c8 mapping > 15-10-2014 13:05:03.690 UTC Verbose sproutletproxy.cpp:1337: > mmtel-0x7f77f0253110 pass initial request Request msg > INVITE/cseq=1789968436 (tdta0x7f77f0614520) to Sproutlet > 15-10-2014 13:05:03.690 UTC Debug sproutletappserver.cpp:70: Store onward > route-set for request > 15-10-2014 13:05:03.690 UTC Debug sproutletappserver.cpp:76: Store header: > Route: <sip:[email protected]:5054;lr;orig> > 15-10-2014 13:05:03.691 UTC Debug mmtel.cpp:578: Originating > Identification Presentation Restriction disabled > 15-10-2014 13:05:03.691 UTC Debug mmtel.cpp:638: Outbound call barring > disabled > 15-10-2014 13:05:03.691 UTC Debug sproutletappserver.cpp:183: Restore > header: Route: <sip:[email protected]:5054;lr;orig> > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1137: Sproutlet > send_request 0x7f77f0614b30 > 15-10-2014 13:05:03.691 UTC Verbose sproutletproxy.cpp:1162: > mmtel-0x7f77f0253110 sending Request msg INVITE/cseq=1789968436 > (tdta0x7f77f0614520) on fork 0 > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 0 responses, 1 requests > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1513: Processing > request 0x7f77f06145c8, fork = 0 > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1631: > mmtel-0x7f77f0253110 transmitting request on fork 0 > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1645: > mmtel-0x7f77f0253110 store reference to non-ACK request Request msg > INVITE/cseq=1789968436 (tdta0x7f77f0614520) on fork 0 > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f0614b30 => txdata 0x7f77f06145c8 mapping > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:110: Find target > Sproutlet for request > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:144: Found next > routable URI: sip:[email protected]:5054;lr;orig > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:260: Found user - > odi_Z6sxALGUH+ > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:260: Found user - > odi_Z6sxALGUH+ > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:260: Found user - > odi_Z6sxALGUH+ > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:260: Found user - > odi_Z6sxALGUH+ > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:174: No Sproutlet > found using service name or host > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:179: Find default > service for port 5054 > 15-10-2014 13:05:03.691 UTC Debug scscfsproutlet.cpp:312: S-CSCF > Transaction (0x7f77f05abf30) created > 15-10-2014 13:05:03.691 UTC Verbose sproutletproxy.cpp:977: Created > Sproutlet scscf-0x7f77f05abf30 for Request msg INVITE/cseq=1789968436 > (tdta0x7f77f0614520) > 15-10-2014 13:05:03.691 UTC Info pjutils.cpp:1551: Cloning header! > 140153110747704 > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f0617aa0 => txdata 0x7f77f0617538 mapping > 15-10-2014 13:05:03.691 UTC Verbose sproutletproxy.cpp:1366: > mmtel-0x7f77f0253110 received provisional response Response msg > 100/INVITE/cseq=1789968436 (tdta0x7f77f0617490) on fork 0, state = > Proceeding > 15-10-2014 13:05:03.691 UTC Verbose sproutletproxy.cpp:1189: > mmtel-0x7f77f0253110 sending Response msg 100/INVITE/cseq=1789968436 > (tdta0x7f77f0617490) > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 1 responses, 0 requests > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1553: Aggregating > response with status code 100 > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1570: Discard > 100/INVITE response (tdta0x7f77f0617490) > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f0617aa0 => txdata 0x7f77f0617538 mapping > 15-10-2014 13:05:03.691 UTC Debug pjsip: tdta0x7f77f061 Destroying txdata > Response msg 100/INVITE/cseq=1789968436 (tdta0x7f77f0617490) > 15-10-2014 13:05:03.691 UTC Debug pjutils.cpp:686: Cloned > tdta0x7f77f0614520 to tdta0x7f77f0617490 > 15-10-2014 13:05:03.691 UTC Debug sproutletproxy.cpp:1038: Remove top > Route header Route: <sip:[email protected] > :5054;lr;orig> > 15-10-2014 13:05:03.692 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f0617aa0 => txdata 0x7f77f0617538 mapping > 15-10-2014 13:05:03.692 UTC Verbose sproutletproxy.cpp:1337: > scscf-0x7f77f05abf30 pass initial request Request msg > INVITE/cseq=1789968436 (tdta0x7f77f0617490) to Sproutlet > 15-10-2014 13:05:03.692 UTC Info scscfsproutlet.cpp:342: S-CSCF received > initial request > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:583: Route header > references this system > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:596: Found ODI token > Z6sxALGUH+ > 15-10-2014 13:05:03.692 UTC Debug aschain.h:124: AsChain inc ref > 0x7f77f0540770 -> 2 > 15-10-2014 13:05:03.692 UTC Info scscfsproutlet.cpp:603: Original dialog > for odi_Z6sxALGUH+ found: AsChain-orig[0x7f77f0540770]:2/1 > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:618: Got our Route > header, session case orig, OD=AsChain-orig[0x7f77f0540770]:2/1 > 15-10-2014 13:05:03.692 UTC Debug pjutils.cpp:344: Served user from > P-Served-User header > 15-10-2014 13:05:03.692 UTC Debug pjutils.cpp:239: aor_from_uri converted > sip:[email protected] to sip:[email protected] > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:681: Retrieved ACR > 0x7f77f0540560 for existing AS chain > 15-10-2014 13:05:03.692 UTC Info scscfsproutlet.cpp:394: Found served > user, so apply services > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:836: Performing > originating initiating request processing > 15-10-2014 13:05:03.692 UTC Info scscfsproutlet.cpp:861: Completed > applying originating services > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:1223: Translating URI > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:218: ENUM is enabled > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:225: SIP URI - user = > 87100001 > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:244: Global number or > look-ups allowed for non-global numbers > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:250: Performing ENUM > lookup for user 87100001 > 15-10-2014 13:05:03.692 UTC Debug enumservice.cpp:186: Translating URI via > JSON ENUM lookup > 15-10-2014 13:05:03.692 UTC Debug enumservice.cpp:233: Comparing first 3 > numbers of 87100001 against prefix 871 > 15-10-2014 13:05:03.692 UTC Debug enumservice.cpp:240: Match found > 15-10-2014 13:05:03.692 UTC Info enumservice.cpp:216: Number 87100001 > found, translated URI = sip:[email protected] > 15-10-2014 13:05:03.692 UTC Debug scscfsproutlet.cpp:1234: Update request > URI to sip:[email protected] > 15-10-2014 13:05:03.692 UTC Info scscfsproutlet.cpp:1025: Routing to > I-CSCF sip:ppsprout1.ims.prod.lan:5052;transport=TCP > 15-10-2014 13:05:03.692 UTC Debug sproutletproxy.cpp:1137: Sproutlet > send_request 0x7f77f0617aa0 > 15-10-2014 13:05:03.692 UTC Verbose sproutletproxy.cpp:1162: > scscf-0x7f77f05abf30 sending Request msg INVITE/cseq=1789968436 > (tdta0x7f77f0617490) on fork 0 > 15-10-2014 13:05:03.692 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 0 responses, 1 requests > 15-10-2014 13:05:03.692 UTC Debug sproutletproxy.cpp:1513: Processing > request 0x7f77f0617538, fork = 0 > 15-10-2014 13:05:03.692 UTC Debug sproutletproxy.cpp:1631: > scscf-0x7f77f05abf30 transmitting request on fork 0 > 15-10-2014 13:05:03.692 UTC Debug sproutletproxy.cpp:1645: > scscf-0x7f77f05abf30 store reference to non-ACK request Request msg > INVITE/cseq=1789968436 (tdta0x7f77f0617490) on fork 0 > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f0617aa0 => txdata 0x7f77f0617538 mapping > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:110: Find target > Sproutlet for request > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:144: Found next > routable URI: sip:ppsprout1.ims.prod.lan:5052;transport=TCP;lr > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:174: No Sproutlet > found using service name or host > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:179: Find default > service for port 5052 > 15-10-2014 13:05:03.693 UTC Verbose sproutletproxy.cpp:977: Created > Sproutlet icscf-0x7f77f0475e70 for Request msg INVITE/cseq=1789968436 > (tdta0x7f77f0617490) > 15-10-2014 13:05:03.693 UTC Info pjutils.cpp:1551: Cloning header! > 140153110759848 > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f061aa10 => txdata 0x7f77f061a4a8 mapping > 15-10-2014 13:05:03.693 UTC Verbose sproutletproxy.cpp:1366: > scscf-0x7f77f05abf30 received provisional response Response msg > 100/INVITE/cseq=1789968436 (tdta0x7f77f061a400) on fork 0, state = > Proceeding > 15-10-2014 13:05:03.693 UTC Info scscfsproutlet.cpp:448: S-CSCF received > response > 15-10-2014 13:05:03.693 UTC Verbose sproutletproxy.cpp:1189: > scscf-0x7f77f05abf30 sending Response msg 100/INVITE/cseq=1789968436 > (tdta0x7f77f061a400) > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 1 responses, 0 requests > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1553: Aggregating > response with status code 100 > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1570: Discard > 100/INVITE response (tdta0x7f77f061a400) > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f061aa10 => txdata 0x7f77f061a4a8 mapping > 15-10-2014 13:05:03.693 UTC Debug pjsip: tdta0x7f77f061 Destroying txdata > Response msg 100/INVITE/cseq=1789968436 (tdta0x7f77f061a400) > 15-10-2014 13:05:03.693 UTC Debug pjutils.cpp:686: Cloned > tdta0x7f77f0617490 to tdta0x7f77f061a400 > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1038: Remove top > Route header Route: <sip:ppsprout1.ims.prod.lan:5052;transport=TCP;lr> > 15-10-2014 13:05:03.693 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f061aa10 => txdata 0x7f77f061a4a8 mapping > 15-10-2014 13:05:03.693 UTC Verbose sproutletproxy.cpp:1337: > icscf-0x7f77f0475e70 pass initial request Request msg > INVITE/cseq=1789968436 (tdta0x7f77f061a400) to Sproutlet > 15-10-2014 13:05:03.693 UTC Debug acr.cpp:1495: Create RalfACR for node > type BGCF with role Terminating > 15-10-2014 13:05:03.693 UTC Debug acr.cpp:48: Created ACR (0x7f77f0100680) > 15-10-2014 13:05:03.693 UTC Debug acr.cpp:195: Created BGCF Ralf ACR > 15-10-2014 13:05:03.693 UTC Debug acr.cpp:274: Set record type for I-CSCF, > BGCF, IBCF, AS to EVENT_RECORD > 15-10-2014 13:05:03.693 UTC Debug acr.cpp:1307: Found P-Charging-Vector > header, store information > 15-10-2014 13:05:03.693 UTC Debug icscfsproutlet.cpp:405: I-CSCF > initialize transaction for non-REGISTER request > 15-10-2014 13:05:03.693 UTC Debug icscfsproutlet.cpp:427: Terminating > request > 15-10-2014 13:05:03.693 UTC Debug icscfrouter.cpp:362: Perform LIR - impu > sip:[email protected], originating false, auth_type None > 15-10-2014 13:05:03.693 UTC Debug httpresolver.cpp:70: > HttpResolver::resolve for host pphomestead1.ims.prod.lan, port 8888, family > 2 > 15-10-2014 13:05:03.693 UTC Debug baseresolver.cpp:511: Attempt to parse > pphomestead1.ims.prod.lan as IP address > 15-10-2014 13:05:03.694 UTC Debug dnscachedresolver.cpp:179: Pulling 1 > records from cache for pphomestead1.ims.prod.lan A > 15-10-2014 13:05:03.694 UTC Debug baseresolver.cpp:359: Found 1 A/AAAA > records, randomizing > 15-10-2014 13:05:03.694 UTC Debug baseresolver.cpp:501: 10.99.99.166:8888 > transport 6 is not blacklisted > 15-10-2014 13:05:03.694 UTC Debug baseresolver.cpp:380: Added a server, > now have 1 of 5 > 15-10-2014 13:05:03.694 UTC Debug baseresolver.cpp:418: Adding 0 servers > from blacklist > 15-10-2014 13:05:03.694 UTC Debug baseresolver.cpp:511: Attempt to parse > 10.99.99.166 as IP address > 15-10-2014 13:05:03.694 UTC Debug httpconnection.cpp:540: Sending HTTP > request : > http://pphomestead1.ims.prod.lan:8888/impu/sip%3A87100001%40ims.prod.lan/location > (trying 10.99.99.166) > 15-10-2014 13:05:03.700 UTC Debug httpconnection.cpp:553: Received HTTP > response : > {"result-code":2003,"mandatory-capabilities":[],"optional-capabilities":[]} > 15-10-2014 13:05:03.700 UTC Debug icscfrouter.cpp:208: HSS returned > capabilities > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:638: Storing Server-Capabilities > 15-10-2014 13:05:03.700 UTC Verbose acr.cpp:646: Sending BGCF Ralf ACR > (0x7f77f0100680) > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:662: Building message > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:677: Adding peers meta-data, 0 > ccfs, 0 ecfs > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:694: Building event > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:698: Adding Account-Record-Type > AVP 1 > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:711: Adding Service-Information > AVP group > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:732: Adding IMS-Information AVP > group > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:736: Adding Event-Type AVP group > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:753: Adding 0 > Calling-Party-Address AVPs > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:764: Adding Called-Party-Address > AVP > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:805: Adding Time-Stamps AVP group > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:845: Adding > Inter-Operator-Identifier AVP group > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:858: Adding 0 Transit-IOI-List > AVPs > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:999: Adding 0 Reason-Header AVPs > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:1008: Adding 0 > Access-Network-Information AVPs > 15-10-2014 13:05:03.700 UTC Debug acr.cpp:1017: Adding From-Address AVP > 15-10-2014 13:05:03.700 UTC Debug httpresolver.cpp:70: > HttpResolver::resolve for host ppralf1.ims.prod.lan, port 10888, family 2 > 15-10-2014 13:05:03.700 UTC Debug baseresolver.cpp:511: Attempt to parse > ppralf1.ims.prod.lan as IP address > 15-10-2014 13:05:03.700 UTC Debug dnscachedresolver.cpp:179: Pulling 1 > records from cache for ppralf1.ims.prod.lan A > 15-10-2014 13:05:03.700 UTC Debug baseresolver.cpp:359: Found 1 A/AAAA > records, randomizing > 15-10-2014 13:05:03.700 UTC Debug baseresolver.cpp:501: 10.99.99.164:10888 > transport 6 is not blacklisted > 15-10-2014 13:05:03.700 UTC Debug baseresolver.cpp:380: Added a server, > now have 1 of 5 > 15-10-2014 13:05:03.700 UTC Debug baseresolver.cpp:418: Adding 0 servers > from blacklist > 15-10-2014 13:05:03.701 UTC Debug httpconnection.cpp:540: Sending HTTP > request : > http://ppralf1.ims.prod.lan:10888/call-id/100929623_64943439%4010.20.7.34 > (trying 10.99.99.164) on new connection > 15-10-2014 13:05:03.702 UTC Debug httpconnection.cpp:833: Received header > http/1.1200ok with value > 15-10-2014 13:05:03.702 UTC Debug httpconnection.cpp:833: Received header > content-length with value 0 > 15-10-2014 13:05:03.702 UTC Debug httpconnection.cpp:833: Received header > with value > 15-10-2014 13:05:03.702 UTC Debug httpconnection.cpp:553: Received HTTP > response : > 15-10-2014 13:05:03.702 UTC Warning scscfselector.cpp:165: There are no > configured S-CSCFs > 15-10-2014 13:05:03.702 UTC Debug icscfrouter.cpp:122: SCSCF selected: > 15-10-2014 13:05:03.702 UTC Info pjutils.cpp:1551: Cloning header! > 140153110771920 > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.702 UTC Verbose sproutletproxy.cpp:1189: > icscf-0x7f77f0475e70 sending Response msg 600/INVITE/cseq=1789968436 > (tdta0x7f77f061d370) > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f061aa10 => txdata 0x7f77f061a4a8 mapping > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1266: Free message > tdta0x7f77f061a400 > 15-10-2014 13:05:03.702 UTC Debug pjsip: tdta0x7f77f061 Destroying txdata > Request msg INVITE/cseq=1789968436 (tdta0x7f77f061a400) > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 1 responses, 0 requests > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1553: Aggregating > response with status code 600 > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1603: 3xx/4xx/5xx/6xx > response > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1607: Best > 3xx/4xx/5xx/6xx response so far > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1500: All UAC > responded > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.702 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.702 UTC Verbose sproutletproxy.cpp:1377: > scscf-0x7f77f05abf30 received final response Response msg > 600/INVITE/cseq=1789968436 (tdta0x7f77f061d370) on fork 0, state = > Terminated > 15-10-2014 13:05:03.703 UTC Info scscfsproutlet.cpp:448: S-CSCF received > response > 15-10-2014 13:05:03.703 UTC Debug acr.cpp:1307: Found P-Charging-Vector > header, store information > 15-10-2014 13:05:03.703 UTC Verbose sproutletproxy.cpp:1189: > scscf-0x7f77f05abf30 sending Response msg 600/INVITE/cseq=1789968436 > (tdta0x7f77f061d370) > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 1 responses, 0 requests > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1553: Aggregating > response with status code 600 > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1603: 3xx/4xx/5xx/6xx > response > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1607: Best > 3xx/4xx/5xx/6xx response so far > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1500: All UAC > responded > 15-10-2014 13:05:03.703 UTC Debug acr.cpp:598: Failed to start session, > change record type to EVENT_RECORD > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.703 UTC Verbose sproutletproxy.cpp:1377: > mmtel-0x7f77f0253110 received final response Response msg > 600/INVITE/cseq=1789968436 (tdta0x7f77f061d370) on fork 0, state = > Terminated > 15-10-2014 13:05:03.703 UTC Verbose sproutletproxy.cpp:1189: > mmtel-0x7f77f0253110 sending Response msg 600/INVITE/cseq=1789968436 > (tdta0x7f77f061d370) > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 1 responses, 0 requests > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1553: Aggregating > response with status code 600 > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1603: 3xx/4xx/5xx/6xx > response > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1607: Best > 3xx/4xx/5xx/6xx response so far > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1500: All UAC > responded > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1463: Adding message > 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.703 UTC Verbose sproutletproxy.cpp:1377: > scscf-0x7f77f01d3030 received final response Response msg > 600/INVITE/cseq=1789968436 (tdta0x7f77f061d370) on fork 0, state = > Terminated > 15-10-2014 13:05:03.703 UTC Info scscfsproutlet.cpp:448: S-CSCF received > response > 15-10-2014 13:05:03.703 UTC Verbose sproutletproxy.cpp:1189: > scscf-0x7f77f01d3030 sending Response msg 600/INVITE/cseq=1789968436 > (tdta0x7f77f061d370) > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1478: Processing > actions from sproutlet - 1 responses, 0 requests > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1553: Aggregating > response with status code 600 > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1603: 3xx/4xx/5xx/6xx > response > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1607: Best > 3xx/4xx/5xx/6xx response so far > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1500: All UAC > responded > 15-10-2014 13:05:03.703 UTC Debug sproutletproxy.cpp:1470: Removing > message 0x7f77f061d980 => txdata 0x7f77f061d418 mapping > 15-10-2014 13:05:03.703 UTC Debug pjsip: tsx0x7f77f05a6 Sending Response > msg 600/INVITE/cseq=1789968436 (tdta0x7f77f061d370) in state Proceeding > 15-10-2014 13:05:03.703 UTC Debug pjsip: tdta0x7f77f055 Destroying txdata > Response msg 100/INVITE/cseq=1789968436 (tdta0x7f77f055cfe0) > 15-10-2014 13:05:03.704 UTC Verbose stack.cpp:249: TX 593 bytes Response > msg 600/INVITE/cseq=1789968436 (tdta0x7f77f061d370) to UDP 10.20.7.34:5060 > : > --start msg-- > > SIP/2.0 600 Busy Everywhere > Via: SIP/2.0/UDP 195.78.8.215:5060 > ;received=10.20.7.34;branch=z9hG4bK04B09231bee4f8f50f3 > Record-Route: > <sip:ppsprout1.ims.prod.lan:5054;transport=TCP;lr;service=scscf;billing-role=charge-orig> > Call-ID: [email protected] > From: "Ru" <sip:[email protected];pstn-params=9084818088 > ;cpc=ordinary>;tag=gK0407d837 > To: <sip:[email protected]>;tag=z9hG4bK04B09231bee4f8f50f3 > CSeq: 1789968436 INVITE > P-Charging-Vector: > icid-value="c0d4c060-369a-1032-00-00-00-10-6b-03-58-67";orig-ioi=ims.prod.lan;icid-generated-at=10.20.7.34 > Content-Length: 0 > > _______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/listinfo/clearwater
