14:14:27:831 01:06:2018 vCUSP,9.1.6,SRV-CUSP11A,10.85.101.44,trace.log [DsTransportListener-0] DEBUG 2018.01.06 14:14:33:388 DsSipLlApi.Wire - Received UDP packet on 10.85.101.44:5060 ,source 10.85.101.16:5060 OPTIONS sip:10.85.101.44:5060 SIP/2.0 Via: SIP/2.0/UDP 10.85.101.16:5060;branch=z9hG4bK6786d2130043ae From: ;tag=481674246 To: Date: Sat, 06 Jan 2018 11:14:33 GMT Call-ID: c45cb800-a501af99-239639-1065550a@10.85.101.16 User-Agent: Cisco-CUCM8.6 CSeq: 101 OPTIONS Contact: Max-Forwards: 0 Content-Length: 0 --- end of packet --- [DATAI.15] DEBUG 2018.01.06 14:14:33:388 DsSipLlApi.Wire - Sending UDP packet on 10.85.101.44:32771, destination 10.85.101.16:5060 SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.85.101.16:5060;branch=z9hG4bK6786d2130043ae To: From: ;tag=481674246 Call-ID: c45cb800-a501af99-239639-1065550a@10.85.101.16 CSeq: 101 OPTIONS Content-Length: 0 Allow: ACK Allow: CANCEL [DsTransportListener-0] DEBUG 2018.01.06 14:14:33:517 DsSipLlApi.Wire - Received UDP packet on 10.85.101.44:5060 ,source 10.20.0.166:52507 INVITE sip:08083327700@10.85.101.44:5060 SIP/2.0 Via: SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 Remote-Party-ID: ;party=calling;screen=yes;privacy=off From: ;tag=90E8394-AB2 To: Date: Sat, 06 Jan 2018 11:14:33 GMT Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 Supported: 100rel,timer,resource-priority,replaces Min-SE: 1800 Cisco-Guid: 2607369622-4060680679-2865889299-3302836656 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER CSeq: 101 INVITE Max-Forwards: 70 Timestamp: 1515237273 Contact: Expires: 180 Allow-Events: telephone-event Content-Type: multipart/mixed;boundary=uniqueBoundary Mime-Version: 1.0 Content-Length: 658 --uniqueBoundary Content-Type: application/sdp Content-Disposition: session;handling=required v=0 o=CiscoSystemsSIP-GW-UserAgent 7950 663 IN IP4 10.20.0.166 s=SIP Call c=IN IP4 10.20.0.166 t=0 0 m=audio 18580 RTP/AVP 0 101 19 c=IN IP4 10.20.0.166 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:19 CN/8000 a=ptime:20 --uniqueBoundary Content-Type: application/gtd Content-Disposition: signal;handling=optional IAM, PRN,isdn*,,NET5*, USI,rate,c,s,c,1 USI,lay1,alaw TMR,00 CPN,00,,1,3327700 CGN,00,,u,y,4,8122750 CPC,09 FCI,,,,,,,y, GCI,9b694d96f20911e7aad20013c4dd49b0 --uniqueBoundary-- --- end of packet --- [REQUESTI.4] DEBUG 2018.01.06 14:14:33:518 DsSipLlApi.Wire - Sending UDP packet on 10.85.101.44:32788, destination 10.20.0.166:5060 SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 To: From: ;tag=90E8394-AB2 Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Timestamp: 1515237273 Content-Length: 0 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:518 util.Normalization - Entering Normalization(moduleRequest:pre-normalize) [REQUESTI.4] INFO 2018.01.06 14:14:33:518 util.Normalization - skipping pre-normalize, due to either no trigger is configured or triggers did not evaluate to true or is configured to by-pass [REQUESTI.4] DEBUG 2018.01.06 14:14:33:519 conditions.RegexCondition - inNetwork='Network_Side_A' [REQUESTI.4] DEBUG 2018.01.06 14:14:33:519 conditions.RegexCondition - IN_NETWORK: Network_Side_A [REQUESTI.4] DEBUG 2018.01.06 14:14:33:519 conditions.AbstractRegexCondition - pattern(^\QNetwork_Side_A\E$), toMatch(Network_Side_A) returning true [REQUESTI.4] DEBUG 2018.01.06 14:14:33:519 triggers.ModuleTrigger - ModuleTrigger.eval() action actionParameter<> [REQUESTI.4] DEBUG 2018.01.06 14:14:33:519 triggers.ModuleTrigger - ModuleTrigger.eval() got the policy, executing it ... [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:520 modules.XCLLookup - table=CVP-ROUTE-TABLE, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:520 modules.XCLLookup - table is CVP-ROUTE-TABLE [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Looking up 08083327700 in table CVP-ROUTE-TABLE with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:521 routingtables.RoutingTable - key 08083327700 was not found in table CVP-ROUTE-TABLE [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 modules.XCLLookup - table=BSPB-PSTN-ROUTE-TABLE, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:521 modules.XCLLookup - table is BSPB-PSTN-ROUTE-TABLE [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Looking up 08083327700 in table BSPB-PSTN-ROUTE-TABLE with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:521 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:522 routingtables.RoutingTable - key 08083327700 was not found in table BSPB-PSTN-ROUTE-TABLE [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 modules.XCLLookup - table=CVP-ROUTE-TABLE-ARENDA, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:522 modules.XCLLookup - table is CVP-ROUTE-TABLE-ARENDA [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 routingtables.RoutingTable - Looking up 08083327700 in table CVP-ROUTE-TABLE-ARENDA with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:522 routingtables.RoutingTable - key 08083327700 was not found in table CVP-ROUTE-TABLE-ARENDA [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:522 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 modules.XCLLookup - table=0820-Peterstar-SPB-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:523 modules.XCLLookup - table is 0820-Peterstar-SPB-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Looking up 08083327700 in table 0820-Peterstar-SPB-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:523 routingtables.RoutingTable - key 08083327700 was not found in table 0820-Peterstar-SPB-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 modules.XCLLookup - table=0821-Equant-SPB-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:523 modules.XCLLookup - table is 0821-Equant-SPB-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Looking up 08083327700 in table 0821-Equant-SPB-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:523 routingtables.RoutingTable - key 08083327700 was not found in table 0821-Equant-SPB-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:523 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 modules.XCLLookup - table=0822-Obit-6775050-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:524 modules.XCLLookup - table is 0822-Obit-6775050-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Looking up 08083327700 in table 0822-Obit-6775050-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:524 routingtables.RoutingTable - key 08083327700 was not found in table 0822-Obit-6775050-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 modules.XCLLookup - table=0823-MTS-OST-Bank-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:524 modules.XCLLookup - table is 0823-MTS-OST-Bank-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Looking up 08083327700 in table 0823-MTS-OST-Bank-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:524 routingtables.RoutingTable - key 08083327700 was not found in table 0823-MTS-OST-Bank-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:524 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 modules.XCLLookup - table=0830-Rostelecom-Kirishi-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:525 modules.XCLLookup - table is 0830-Rostelecom-Kirishi-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Looking up 08083327700 in table 0830-Rostelecom-Kirishi-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:525 routingtables.RoutingTable - key 08083327700 was not found in table 0830-Rostelecom-Kirishi-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 modules.XCLLookup - table=0840-Equant-Moscow-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:525 modules.XCLLookup - table is 0840-Equant-Moscow-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Looking up 08083327700 in table 0840-Equant-Moscow-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:525 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:526 routingtables.RoutingTable - key 08083327700 was not found in table 0840-Equant-Moscow-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 modules.XCLLookup - table=0841-Rostelekom-Kaliningrad-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:526 modules.XCLLookup - table is 0841-Rostelekom-Kaliningrad-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Looking up 08083327700 in table 0841-Rostelekom-Kaliningrad-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:526 routingtables.RoutingTable - key 08083327700 was not found in table 0841-Rostelekom-Kaliningrad-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 modules.XCLLookup - table=0842-Megafon-Kaliningrad-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:526 modules.XCLLookup - table is 0842-Megafon-Kaliningrad-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:526 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Looking up 08083327700 in table 0842-Megafon-Kaliningrad-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:527 routingtables.RoutingTable - key 08083327700 was not found in table 0842-Megafon-Kaliningrad-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 modules.XCLLookup - table=0860-MTS-Arenda-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:527 modules.XCLLookup - table is 0860-MTS-Arenda-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Looking up 08083327700 in table 0860-MTS-Arenda-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:527 routingtables.RoutingTable - key 08083327700 was not found in table 0860-MTS-Arenda-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:527 modules.XCLLookup - table=0861-Megafon-Arenda-Incoming-from-PSTN, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:527 modules.XCLLookup - table is 0861-Megafon-Arenda-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Looking up 08083327700 in table 0861-Megafon-Arenda-Incoming-from-PSTN with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:528 routingtables.RoutingTable - key 08083327700 was not found in table 0861-Megafon-Arenda-Incoming-from-PSTN [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 nrs.XCLNRSShiftRoutes - Entering ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 nrs.XCLNRSShiftRoutes - Leaving ShiftAlgorithms.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 modules.XCLLookup - Entering execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 nrs.XCLPrefix - Entering getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 nrs.FieldSelector - getUriPart: URI - sip:08083327700@10.85.101.44:5060 part 6 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 nrs.FieldSelector - Requested field 45 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 nrs.FieldSelector - Returning key 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 nrs.XCLPrefix - Leaving getKeyValue() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 modules.XCLLookup - table=0808-TO-CUCM8, key=08083327700 [REQUESTI.4] INFO 2018.01.06 14:14:33:528 modules.XCLLookup - table is 0808-TO-CUCM8 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Entering lookup() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Looking up 08083327700 in table 0808-TO-CUCM8 with rule prefix and modifiers=none [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Entering applyModifiers() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Leaving applyModifiers(), returning 08083327700 [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 routingtables.RoutingTable - Leaving lookup() [REQUESTI.4] INFO 2018.01.06 14:14:33:528 nrs.XCLPrefix - NRS Routing decision is: RouteTable:0808-TO-CUCM8, RouteKey:0808, TargetDestination:BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, Network:Network_Side_A [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 loadbalancer.LBFactory - Entering createLoadBalancer() [REQUESTI.4] INFO 2018.01.06 14:14:33:528 loadbalancer.LBFactory - lbtype is 3(call-id) [REQUESTI.4] DEBUG 2018.01.06 14:14:33:528 loadbalancer.LBFactory - Leaving createLoadBalancer() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.XCLPrefix - Stored NRSAlgResult=isFound=true, isFailure=false, Response=-1, Routes=[Ruri: BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, Route: null, Network: Network_Side_A, q-value=1.0radvance=[502, 503]], PolicyAdvance=null [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.NRSAlgResult - set policyAdvance as specified in route=RouteTable:0808-TO-CUCM8, RouteKey:0808, TargetDestination:BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, Network:Network_Side_A [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.NRSAlgResult - no policyAdvance specified in route [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.NRSAlgResult - set policyAdvance as specified in algorithm={lookuprule=1, lookupfield=45, lookuplenght=-1, lookuptable=0808-TO-CUCM8, sequence=1400, algorithm=1} [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.NRSAlgResult - no policyAdvance specified in algorithm [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 modules.XCLLookup - Leaving execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.XCLNRSShiftRoutes - Entering ShiftRoutes.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 loadbalancer.LBBase - Entering getServer() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 loadbalancer.LBBase - Entering initializeDomains() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.NRSRoutes - routes before applying time policies: [Ruri: BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, Route: null, Network: Network_Side_A, q-value=1.0radvance=[502, 503]] [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.NRSRoutes - routes after applying time policies: [Ruri: BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, Route: null, Network: Network_Side_A, q-value=1.0radvance=[502, 503]] [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 loadbalancer.LBBase - Leaving initializeDomains() [REQUESTI.4] INFO 2018.01.06 14:14:33:529 loadbalancer.LBHashBased - list of elements in order on which load balancing is done : Ruri: BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, Route: null, Network: Network_Side_A, q-value=1.0radvance=[502, 503], [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 loadbalancer.LBBase - Server group route-sg selected Ruri: BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, Route: null, Network: Network_Side_A, q-value=1.0radvance=[502, 503] [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 loadbalancer.LBBase - Leaving getServer() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:529 nrs.XCLNRSShiftRoutes - Leaving ShiftRoutes.execute() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 loadbalancer.LBFactory - Entering createLoadBalancer() [REQUESTI.4] INFO 2018.01.06 14:14:33:530 loadbalancer.LBFactory - lbtype is 1(highest-q) [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 loadbalancer.LBFactory - Leaving createLoadBalancer() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 loadbalancer.LBBase - Entering getServer() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 loadbalancer.LBBase - Entering initializeDomains() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 servergroups.ServerGlobalStateWrapper - Network_Side_A:10.85.101.16:5060:1 numTries=2--->isServerAvailable(): true [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 servergroups.ServerGlobalStateWrapper - Network_Side_A:10.66.101.16:5060:1 numTries=2--->isServerAvailable(): true [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 servergroups.ServerGlobalStateWrapper - Network_Side_A:10.85.101.15:5060:1 numTries=2--->isServerAvailable(): true [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 loadbalancer.LBBase - Leaving initializeDomains() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 servergroups.AbstractNextHop - Entering compareDomainNames() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 servergroups.AbstractNextHop - Leaving compareDomainNames() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 loadbalancer.LBBase - Server group BspbCiscoUnifiedCallManagerCluster8.bankspb.ru selected {reSgElementWeight=0, reSgElementSgName=BspbCiscoUnifiedCallManagerCluster8.bankspb.ru, reSgElementTransport=UDP, reSgElementQValue=1.0, reSgElementPort=5060, reSgElementHost=10.85.101.16} [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 loadbalancer.LBBase - Leaving getServer() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 util.Normalization - Entering Normalization(moduleRequest:post-normalize) [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 conditions.RegexCondition - outNetwork='Network_Side_A', resp='null' [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 conditions.RegexCondition - OUT_NETWORK: null [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 conditions.RegexCondition - OUT_NETWORK: Network_Side_A [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 conditions.AbstractRegexCondition - pattern(^\QNetwork_Side_A\E$), toMatch(Network_Side_A) returning true [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 triggers.ModuleTrigger - ModuleTrigger.eval() action actionParameter<> [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 triggers.ModuleTrigger - ModuleTrigger.eval() got the policy, executing it ... [REQUESTI.4] DEBUG 2018.01.06 14:14:33:530 normalization.URIComponentNormalizationAlgorithm - normalizing request-uri [REQUESTI.4] DEBUG 2018.01.06 14:14:33:531 util.Normalization - Leaving Normalization.normalize() [REQUESTI.4] DEBUG 2018.01.06 14:14:33:532 DsSipLlApi.Wire - Sending UDP packet on 10.85.101.44:32771, destination 10.85.101.16:5060 INVITE sip:08083327700@BspbCiscoUnifiedCallManagerCluster8.bankspb.ru SIP/2.0 Via: SIP/2.0/UDP 10.85.101.44:5060;branch=z9hG4bKc8HzP6Njej4GCpH.MRlFOQ~~146 Via: SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 Max-Forwards: 69 To: From: ;tag=90E8394-AB2 Contact: Expires: 180 Remote-Party-ID: ;party=calling;screen=yes;privacy=off Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Content-Length: 658 Date: Sat, 06 Jan 2018 11:14:33 GMT Supported: 100rel,timer,resource-priority,replaces Min-SE: 1800 Cisco-Guid: 2607369622-4060680679-2865889299-3302836656 User-Agent: Cisco-SIPGateway/IOS-12.x Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER Timestamp: 1515237273 Allow-Events: telephone-event Content-Type: multipart/mixed;boundary=uniqueBoundary MIME-Version: 1.0 --uniqueBoundary Content-Type: application/sdp Content-Disposition: session;handling=required v=0 o=CiscoSystemsSIP-GW-UserAgent 7950 663 IN IP4 10.20.0.166 s=SIP Call c=IN IP4 10.20.0.166 t=0 0 m=audio 18580 RTP/AVP 0 101 19 c=IN IP4 10.20.0.166 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:19 CN/8000 a=ptime:20 --uniqueBoundary Content-Type: application/gtd Content-Disposition: signal;handling=optional IAM, PRN,isdn*,,NET5*, USI,rate,c,s,c,1 USI,lay1,alaw TMR,00 CPN,00,,1,3327700 CGN,00,,u,y,4,8122750 CPC,09 FCI,,,,,,,y, GCI,9b694d96f20911e7aad20013c4dd49b0 --uniqueBoundary-- [DsTransportListener-0] DEBUG 2018.01.06 14:14:33:533 DsSipLlApi.Wire - Received UDP packet on 10.85.101.44:5060 ,source 10.85.101.16:5060 SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.85.101.44:5060;branch=z9hG4bKc8HzP6Njej4GCpH.MRlFOQ~~146,SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 From: ;tag=90E8394-AB2 To: Date: Sat, 06 Jan 2018 11:14:33 GMT Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Allow-Events: presence Content-Length: 0 --- end of packet --- [DsTransportListener-0] DEBUG 2018.01.06 14:14:33:563 DsSipLlApi.Wire - Received UDP packet on 10.85.101.44:5060 ,source 10.85.101.16:5060 SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.85.101.44:5060;branch=z9hG4bKc8HzP6Njej4GCpH.MRlFOQ~~146,SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 From: ;tag=90E8394-AB2 To: ;tag=182806020~2e7c3f08-3bfc-46a9-acab-c6016bceb370-37110165 Date: Sat, 06 Jan 2018 11:14:33 GMT Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Supported: X-cisco-srtp-fallback Supported: Geolocation P-Asserted-Identity: "7277" Remote-Party-ID: "7277" ;party=called;screen=yes;privacy=off Contact: Content-Length: 0 --- end of packet --- [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:565 DsSipLlApi.Wire - Sending UDP packet on 10.85.101.44:32788, destination 10.20.0.166:5060 SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 To: ;tag=182806020~2e7c3f08-3bfc-46a9-acab-c6016bceb370-37110165 From: ;tag=90E8394-AB2 Contact: Remote-Party-ID: "7277" ;party=called;screen=yes;privacy=off Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Content-Length: 0 Date: Sat, 06 Jan 2018 11:14:33 GMT Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence Supported: X-cisco-srtp-fallback Supported: Geolocation P-Asserted-Identity: "7277" [DsTransportListener-0] DEBUG 2018.01.06 14:14:33:577 DsSipLlApi.Wire - Received UDP packet on 10.85.101.44:5060 ,source 10.85.101.16:5060 SIP/2.0 200 OK Via: SIP/2.0/UDP 10.85.101.44:5060;branch=z9hG4bKc8HzP6Njej4GCpH.MRlFOQ~~146,SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 From: ;tag=90E8394-AB2 To: ;tag=182806020~2e7c3f08-3bfc-46a9-acab-c6016bceb370-37110165 Date: Sat, 06 Jan 2018 11:14:33 GMT Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence, kpml Supported: replaces Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 1800;refresher=uas Require: timer P-Asserted-Identity: Remote-Party-ID: ;party=called;screen=yes;privacy=off Contact: Content-Type: application/sdp Content-Length: 184 v=0 o=CiscoSystemsCCM-SIP 182806020 1 IN IP4 10.85.101.16 s=SIP Call c=IN IP4 10.85.101.17 b=TIAS:64000 b=AS:64 t=0 0 m=audio 25980 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=ptime:20 --- end of packet --- [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:579 util.Normalization - Entering Normalization(moduleRequest:post-normalize) [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 conditions.RegexCondition - outNetwork='Network_Side_A', resp='SIP/2.0 200 OK Via: SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 To: ;tag=182806020~2e7c3f08-3bfc-46a9-acab-c6016bceb370-37110165 From: ;tag=90E8394-AB2 Contact: Require: timer Remote-Party-ID: ;party=called;screen=yes;privacy=off Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Content-Length: 184 Date: Sat, 06 Jan 2018 11:14:33 GMT Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence, kpml Supported: replaces Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 1800;refresher=uas P-Asserted-Identity: Content-Type: application/sdp v=0 o=CiscoSystemsCCM-SIP 182806020 1 IN IP4 10.85.101.16 s=SIP Call c=IN IP4 10.85.101.17 b=TIAS:64000 b=AS:64 t=0 0 m=audio 25980 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=ptime:20 ' [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 conditions.RegexCondition - OUT_NETWORK: null [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 conditions.RegexCondition - OUT_NETWORK: Network_Side_A [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 conditions.AbstractRegexCondition - pattern(^\QNetwork_Side_A\E$), toMatch(Network_Side_A) returning true [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 triggers.ModuleTrigger - ModuleTrigger.eval() action actionParameter<> [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 triggers.ModuleTrigger - ModuleTrigger.eval() got the policy, executing it ... [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 util.Normalization - Leaving Normalization.normalize() [CT_CALLBACK.12] DEBUG 2018.01.06 14:14:33:580 DsSipLlApi.Wire - Sending UDP packet on 10.85.101.44:32788, destination 10.20.0.166:5060 SIP/2.0 200 OK Via: SIP/2.0/UDP 10.20.0.166:5060;branch=z9hG4bKC1BE2 To: ;tag=182806020~2e7c3f08-3bfc-46a9-acab-c6016bceb370-37110165 From: ;tag=90E8394-AB2 Contact: Require: timer Remote-Party-ID: ;party=called;screen=yes;privacy=off Call-ID: 9B6B220E-F20911E7-A93F81E6-99993620@10.20.0.166 CSeq: 101 INVITE Content-Length: 184 Date: Sat, 06 Jan 2018 11:14:33 GMT Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY Allow-Events: presence, kpml Supported: replaces Supported: X-cisco-srtp-fallback Supported: Geolocation Session-Expires: 1800;refresher=uas P-Asserted-Identity: Content-Type: application/sdp v=0 o=CiscoSystemsCCM-SIP 182806020 1 IN IP4 10.85.101.16 s=SIP Call c=IN IP4 10.85.101.17 b=TIAS:64000 b=AS:64 t=0 0 m=audio 25980 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=ptime:20