---------------------------------------- WIP330 REGISTER sip:10.2.200.10:5060;transport=UDP SIP/2.0 From: ;tag=3351e0-a0c8020a-13c4-fd-167863c3-fd To: Call-ID: 338d70-a0c8020a-13c4-fd-1d2431d2-fd CSeq: 1 REGISTER Via: SIP/2.0/UDP 10.2.200.160:5060;rport;branch=z9hG4bK-fd-3de6d-2d404a90 Max-Forwards: 70 Supported: replaces User-Agent: WIP330 Contact: Expires: 60 Content-Length: 0 |::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0x0/context=(nil)|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Info/0x0/Checking Invite Dialog|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Info/0x0/*****CCB NOT found in UAS Request table. ccb=(nil)|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Info/0x0/Added context(0xb416ff10) with key=[65039] to table|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.2.200.160,Port 5060, Transport 1, SentBy Port 5060|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/States/0xb416ff10/0xb416ff10 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.2.200.160,Port 5060, Transport 1, SentBy Port 5060|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.2.200.160,Port 5060, Transport 1, SentBy Port 5060|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0xb416ff10/Sending INFO Response to the transport layer|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0xb416ff10/msg=0xb37364b0, addr=10.2.200.160, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=(nil)|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0xb416ff10/Proceedable for sending msg immediately|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0xb416ff10/switch transport is 0|::> 09/09/2009 16:23:12.906 CCM|//SIP/Stack/Transport/0x0/Posting send for msg=0xb37364b0, addr=10.2.200.160, port=5060, connId=0 for UDP|::> 09/09/2009 16:23:12.906 CCM|//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.2.200.160:[5060]: SIP/2.0 100 Trying Date: Wed, 09 Sep 2009 14:23:12 GMT From: ;tag=3351e0-a0c8020a-13c4-fd-167863c3-fd Content-Length: 0 To: Call-ID: 338d70-a0c8020a-13c4-fd-1d2431d2-fd Via: SIP/2.0/UDP 10.2.200.160:5060;rport;branch=z9hG4bK-fd-3de6d-2d404a90 CSeq: 1 REGISTER |::> 09/09/2009 16:23:12.907 CCM|EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0|::> 09/09/2009 16:23:12.907 CCM|EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 327, 10.2.200.160:5060)| 09/09/2009 16:23:12.907 CCM|//SIP/Stack/Info/0xb416ff10/****Adding to UAS Request table.|::> 09/09/2009 16:23:12.907 CCM|//SIP/Stack/Info/0xb416ff10/Added to table. ccb=0xb416ff10 key=338d70-a0c8020a-13c4-fd-1d2431d2-fd100303351e0-a0c8020a-13c4-fd-167863c3-fd|::> 09/09/2009 16:23:12.907 CCM|//SIP/SIPHandler/ccbId=65275/scbId=0/findDevicePID: Routed to SIPStationInit|::> 09/09/2009 16:23:12.907 CCM|SIPStationInit: connId=0, ,10030@10.2.200.160:5060, New 3rd-party (RFC3261) phone registering|::> 09/09/2009 16:23:12.907 CCM|SIPStationInit: connID=0, , 10.2.200.160:5060, New targetEntry created|::> 09/09/2009 16:23:12.907 CCM|SIPStationInit: connID=0, , 10.2.200.160:5060, (RegTrace 0xe7693c0) Enqueuing register for ccbID 65275|::> 09/09/2009 16:23:12.907 CCM|SIPStationInit: connID=0, , 10.2.200.160:5060, REGISTER Queued|::> 09/09/2009 16:23:12.907 CCM|SIPStationInit: connID=0, , 10.2.200.160:5060, DevStat-InitState: NEW --> ON_NEWREG_QUEUE|::> 09/09/2009 16:23:12.907 CCM|//SIP/Stack/Event/0xb416ff10/ ccsip_api_register_ind return value : SIP_SUCCESS |::> 09/09/2009 16:23:12.907 CCM|//SIP/Stack/Info/0xb416ff10/Adding call id fefb to table|::> 09/09/2009 16:23:12.907 CCM|//SIP/Stack/States/0xb416ff10/0xb416ff10 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE)| 09/09/2009 16:23:12.969 CCM|CMProcMon - ------Entered Router Verification| 09/09/2009 16:23:12.969 CCM|CMProcMon - ----Exited Router Verification| 09/09/2009 16:23:13.018 CCM|SIPStationInit: Processing registration queue|::> 09/09/2009 16:23:13.018 CCM|SIPStationInit: connID=0, , 10.2.200.160:5060, Creating new SIPStationD|::> 09/09/2009 16:23:13.018 CCM|SIPStationInit: connID=0, , 10.2.200.160:5060, DevStat-InitState: ON_NEWREG_QUEUE --> REGISTERED|::> 09/09/2009 16:23:13.018 CCM|SIPStationInit: RegQueueSize=0 MaxEntriesToProcess=40 tokeMax=10 tokeOut=0 Registration Queue Serviced 1 entries in 0 milliseconds|::> 09/09/2009 16:23:13.018 CCM|SIPStationD(1,100,45,301), , UNKNOWN:0, primaryDN=UNKNOWN, Primary expires 120, secondary expires 3600| 09/09/2009 16:23:13.019 CCM|SIPStationD(1,100,45,301), , 10.2.200.160:5060, primaryDN=10030, userStart chars . 0 :|::> 09/09/2009 16:23:13.019 CCM|SIPStationD(1,100,45,301), , 10.2.200.160:5060, primaryDN=10030, wait_register_SIPRegisterInd: model=255, mUaType=0|::> 09/09/2009 16:23:13.019 CCM|SIPStationD(1,100,45,301), , 10.2.200.160:5060, primaryDN=10030, Register instanceId (MAC addr) =NULL|::> 09/09/2009 16:23:13.019 CCM|SIPStationD(1,100,45,301), , 10.2.200.160:5060, primaryDN=10030, Register deviceName (database key) =NULL|::> 09/09/2009 16:23:13.019 CCM|SIPStationD(1,100,45,301), , 10.2.200.160:5060, primaryDN=10030, No instance ID or digest-auth, sending challenge.|::> 09/09/2009 16:23:13.019 CCM|DeviceTransientConnection - Transient connection attempt. Connecting Port:5060 Device name [Optional].: Device IP address [Optional].:10.2.200.160 Protocol.:SIP Device type. [Optional]:336 Reason Code [Optional].:4 Registering SIP User. [Optional].:10030 App ID:Cisco CallManager Cluster ID:StandAloneCluster Node ID:CUCM1| 09/09/2009 16:23:13.019 CCM|SIPStationD(1,100,45,301), , 10.2.200.160:5060, primaryDN=10030, it's first challenge . Set Challenge timeout timer |::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 41|::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/Info/0xb416ff10/Associated container=0xe72c560 to REGISTER Response 401|::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/Transport/0xb416ff10/Sending INFO Response to the transport layer|::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/Transport/0xb416ff10/msg=0xb41dbf28, addr=10.2.200.160, port=5060, sentBy_port=5060, is_req=0, transport=1, switch=0, callBack=0x9ca6e6e|::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/Transport/0xb416ff10/Proceedable for sending msg immediately|::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/Transport/0xb416ff10/switch transport is 0|::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/Transport/0x0/Posting send for msg=0xb41dbf28, addr=10.2.200.160, port=5060, connId=0 for UDP|::> 09/09/2009 16:23:13.020 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0|::> 09/09/2009 16:23:13.020 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0|::> 09/09/2009 16:23:13.020 CCM|//SIP/Stack/States/0xb416ff10/0xb416ff10 : State change from (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)| 09/09/2009 16:23:13.020 CCM|-->RISCMAccess::DeviceTransientConnection(...)| 09/09/2009 16:23:13.020 CCM|Device Transient deviceName : , IPAddress : 10.2.200.160, IPv4Attribute :0, IPv6Attribute :0, Protocol : 2| 09/09/2009 16:23:13.020 CCM|<--RISCMAccess::DeviceTransientConnection(...)| 09/09/2009 16:23:13.020 CCM|//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.2.200.160:[5060]: SIP/2.0 401 Unauthorized Date: Wed, 09 Sep 2009 14:23:12 GMT From: ;tag=3351e0-a0c8020a-13c4-fd-167863c3-fd WWW-Authenticate: Digest realm="ccmsipline", nonce="A5EP8K7nNPz3hHr/WiWF/pD1nGVyUzAp", algorithm=MD5 Content-Length: 0 To: ;tag=1279955569 Call-ID: 338d70-a0c8020a-13c4-fd-1d2431d2-fd Via: SIP/2.0/UDP 10.2.200.160:5060;rport;branch=z9hG4bK-fd-3de6d-2d404a90 CSeq: 1 REGISTER --------------------------------------------------------------------------------------------ç XLITE 09/09/2009 16:24:25.980 CCM|//SIP/SIPUdp/wait_UdpDataInd: Incoming SIP UDP message size 747 from 10.2.200.195:[16352]: REGISTER sip:10.2.200.10 SIP/2.0 Via: SIP/2.0/UDP 10.2.200.195:16352;branch=z9hG4bK-d87543-5722e8026279962a-1--d87543-;rport Max-Forwards: 70 Contact: To: "Callmanager" From: "Callmanager";tag=1047b60b Call-ID: f733d630ff2b6a6dOGNhYzExZWJkOTJlZGE3ZjljOThhMzJiNjk5Y2Q2YTA. CSeq: 2 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite release 1002tx stamp 29712 Authorization: Digest username="10030",realm="ccmsipline",nonce="fP163sh80ez99xyzbhTlxyWD/HpZ/Lhu",uri="sip:10.2.200.10",response="c81b91b2dfe9dd729c9f692c420ea50c",algorithm=MD5 Content-Length: 0 | 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0x0/context=(nil)|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Info/0x0/Checking Invite Dialog|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Info/0xb414fbb8/*****CCB found in UAS Request table. ccb=0xb414fbb8|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.2.200.195,Port 16352, Transport 1, SentBy Port 5060|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.2.200.195,Port 16352, Transport 1, SentBy Port 16352|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0xb414fbb8/Sending INFO Response to the transport layer|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0xb414fbb8/msg=0xb418e498, addr=10.2.200.195, port=16352, sentBy_port=16352, is_req=0, transport=1, switch=0, callBack=(nil)|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0xb414fbb8/Proceedable for sending msg immediately|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0xb414fbb8/switch transport is 0|< 09/09/2009 16:24:25.980 CCM|//SIP/Stack/Transport/0x0/Posting send for msg=0xb418e498, addr=10.2.200.195, port=16352, connId=0 for UDP|< 09/09/2009 16:24:25.980 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0|< 09/09/2009 16:24:25.981 CCM|//SIP/SIPHandler/ccbId=65276/scbId=0/findDevicePID: Routed to SIPStationInit|< 09/09/2009 16:24:25.981 CCM|//SIP/Stack/Event/0xb414fbb8/ ccsip_api_register_ind return value : SIP_SUCCESS |< 09/09/2009 16:24:25.981 CCM|//SIP/Stack/States/0xb414fbb8/0xb414fbb8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE)| 09/09/2009 16:24:25.981 CCM|//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.2.200.195:[16352]: SIP/2.0 100 Trying Date: Wed, 09 Sep 2009 14:24:25 GMT From: "Callmanager";tag=1047b60b Content-Length: 0 To: "Callmanager";tag=1294152932 Call-ID: f733d630ff2b6a6dOGNhYzExZWJkOTJlZGE3ZjljOThhMzJiNjk5Y2Q2YTA. Via: SIP/2.0/UDP 10.2.200.195:16352;branch=z9hG4bK-d87543-5722e8026279962a-1--d87543-;rport CSeq: 2 REGISTER | 09/09/2009 16:24:25.981 CCM|SIPStationInit: connId=0, , 10030@10.2.200.195:16352, 3rd-party (RFC-3261) phone found by contact| 09/09/2009 16:24:25.981 CCM|SIPStationInit: connId=0, ,10030@10.2.200.195:16352, New 3rd-party (RFC3261) phone registering| 09/09/2009 16:24:25.981 CCM|SIPStationInit: connID=0, , 10.2.200.195:16352, Routing to D (1,100,45,302), regCount=2|< 09/09/2009 16:24:25.981 CCM|EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0| 09/09/2009 16:24:25.981 CCM|EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 374, 10.2.200.195:16352)| 09/09/2009 16:24:25.981 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, userStart chars . 0 :| 09/09/2009 16:24:25.981 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, wait_register_SIPRegisterInd: model=255, mUaType=0| 09/09/2009 16:24:25.981 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, Register instanceId (MAC addr) =NULL| 09/09/2009 16:24:25.981 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, Register deviceName (database key) =NULL| 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, DevStat-Start : transport UDP, model 336| 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, No Supported header|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, setDefaultOptions: INFO - user agent 5|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, setOptionsIndicationDefaultOptions: INFO - user agent 5|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, setOptionsIndicationDefaultOptions: ERROR - Unexpected user agent 5| 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, Convert UserId "10030" to deviceName|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), , 10.2.200.195:16352, primaryDN=10030, deviceType=336, mSIPAuthorizationHeaderPtr->user_pass=10030|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, deviceSecurityMode == 1, clusterSecurityMode == 0, exp = 3/1|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, VerFeatMask=0x00000000, hasCrypto=0|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, mtpRequired = 0 useTRP = 0 mtpPrefOrgCodec = 1 unAttendedPort = 0 requireDTMFReception = 0 packetCaptureMode = 0 packetCaptureDuration = 0|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, securityProfileName = Third-party SIP Device Basic - Standard SIP Non-Secure Profile, nonceValidityTime = 600 transportTypeValue = 4 digestAuthorization = 0|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, deviceSecurityMode = 1|< 09/09/2009 16:24:25.982 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, subscribeCSS = css_reroute = css_ood = presenceGroup = ad243d17-98b4-4118-8feb-5ff2e1b781ac dialRules = |< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, versionStamp = 1252504413-f261c21e-6aec-4ceb-b98b-0bd840d44c0c, softkeyTemplateVersionStamp = , dialRulesVersionStamp = |< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, SIPDigestUserEntry: userName = 10030 credentials = 461586b4631bc7ff34debc229f4ec84c89b881c78228a9516fc4714520db1f1d SIPDevice = SEP0016B64DDC0E enduserPresenceGroup = |< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, SIPProfile: SIPProfileInfoName = Standard SIP Profile rerouteType = 0 timeInviteExpires = 180 timerRegDelta = 5 timerRegExpires = 3600|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, SIPProfile: timerT1 = 500, timerT2 = 4000, retryInvite = 6, tryOnInvite = 10, startMediaPort = 16384, ringing180 = 0 defaultTelephonyEvent = 101|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, callPickupURI = [x-cisco-serviceuri-pickup] callPickupGroupURI = [x-cisco-serviceuri-gpickup]|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, callPickupOtherGroupURI = [x-cisco-serviceuri-opickup] meetMeURI = [x-cisco-serviceuri-meetme]|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, callForwardURI = [x-cisco-serviceuri-cfwdall] abbrdialURI = [x-cisco-serviceuri-abbrdial]|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, blfpickupURI = [x-cisco-serviceuri-blfpickup]|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, Device Mobility: MobileDevice = false DevicePoolPkid = 920731a8-a31f-3a3c-638f-865473168456 RoamingDevicePoolPkid = PhysicalLocation = AARNeighborHood = |< 09/09/2009 16:24:25.983 CCM|CcmCcmdbHelper::hrDurationTimer 0, hrIntervalTimer 0, numplan.hrduration 0, numplan.hrinterval 0| 09/09/2009 16:24:25.983 CCM| 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, initLineStruct: Initializing index 1, DN 10030| 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, initLineStruct: MonRec data: recType=0, recDN=, recCSS=, monCSS=|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, mPrivacyButtonConfigured:false isPrivacyOn:true|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, checkDNsReceived: TotalCount=1|< 09/09/2009 16:24:25.983 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, sendLineRegisterReq: mLineRegisterReqsOutstanding=1| 09/09/2009 16:24:25.983 CCM|SMDMSharedData::findAliasRegInfo - AliasName = ff24f82a-df94-f324-6714-d0cb8b5394c4 not in AliasInfo hashmap|< 09/09/2009 16:24:25.984 CCM|DeviceManager::star_DmPidReq - RequestedName=ff24f82a-df94-f324-6714-d0cb8b5394c4 LookupName=ff24f82a-df94-f324-6714-d0cb8b5394c4|< 09/09/2009 16:24:25.984 CCM|SMDMSharedData::findRemoteDeviceAny - Key=ff24f82a-df94-f324-6714-d0cb8b5394c4 not in RemoteDeviceInfo hashmap|< 09/09/2009 16:24:25.984 CCM|LineManager::getLineControlStatIndex - will use id = 6| 09/09/2009 16:24:25.984 CCM|LineControl - Create Stats: index =6| 09/09/2009 16:24:25.984 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, line_register_LineRegisterRes: mLineRegisterReqsOutstanding=0| 09/09/2009 16:24:25.984 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, storeContactHeader: Request-URI is sip:10030@10.2.200.195:16352;rinstance=1afc6f558e0022d7|< 09/09/2009 16:24:25.984 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, Started 1 second partially-registered alarm timer.|< 09/09/2009 16:24:25.984 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, DevStat-NewState : line 10030: Null ==> Registered| 09/09/2009 16:24:25.984 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, Stopped partially-registered alarm timer.|< 09/09/2009 16:24:25.985 CCM|-->RISCMAccess::DeviceRegister(...)| 09/09/2009 16:24:25.985 CCM|Device Register deviceName : SEP0016B64DDC0E, IPAdress : 10.2.200.195, IPv4Attribute : 0, IPv6Attribute : 0, ActiveLoadID : , DeviceType : 336, Protocol : 2| 09/09/2009 16:24:25.985 CCM|DebugMsg deviceName : SEP0016B64DDC0E, DeviceType : 336, risClass: 1| 09/09/2009 16:24:25.985 CCM|SIP Device Register from Unregister: deviceName(SEP0016B64DDC0E), Protocol(2), RegisteredSCCP(4), RegisteredSIP(1)| 09/09/2009 16:24:25.985 CCM|<--RISCMAccess::DeviceRegister(...)| 09/09/2009 16:24:25.987 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, VerFeatMask=0x00000000, hasCrypto=0|< 09/09/2009 16:24:25.987 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, buildCFwdAll: forwardAllDirNum[], forwardAllVoiceMailOnOff[off]|< 09/09/2009 16:24:25.987 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, sendRemoteCCReferReq: Unable to find a line to use| 09/09/2009 16:24:25.987 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, hlog status: 1|< 09/09/2009 16:24:25.987 CCM|SIPStationD(1,100,45,302), SEP0016B64DDC0E, 10.2.200.195:16352, primaryDN=10030, Phone does not support service-control events, not sending NOTIFY|< 09/09/2009 16:24:25.987 CCM|LineControl(52) - SEP0016B64DDC0E registered, CEPN=fba08135-5f60-1fdd-5041-2396367f14f8, deviceType=4|< 09/09/2009 16:24:25.987 CCM|No associated device or user.|< 09/09/2009 16:24:25.988 CCM|LineControl::sendSNFNotifyIndForPresenceWithAlerting mPrecenceWithAlertingChangeNotifySubscribed=0, calllist#=0|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 3 for event 41|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Info/0xb414fbb8/Response Container Holder is above threshold...trimming|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Info/0xb414fbb8/Associated container=0xdee8ab0 to REGISTER Response 200|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Transport/0xb414fbb8/Sending INFO Response to the transport layer|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Transport/0xb414fbb8/msg=0xb418e498, addr=10.2.200.195, port=16352, sentBy_port=16352, is_req=0, transport=1, switch=0, callBack=0x9ca6e6e|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Transport/0xb414fbb8/Proceedable for sending msg immediately|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Transport/0xb414fbb8/switch transport is 0|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/Transport/0x0/Posting send for msg=0xb418e498, addr=10.2.200.195, port=16352, connId=0 for UDP|< 09/09/2009 16:24:25.988 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0|< 09/09/2009 16:24:25.988 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0|< 09/09/2009 16:24:25.988 CCM|//SIP/Stack/States/0xb414fbb8/0xb414fbb8 : State change from (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE)| 09/09/2009 16:24:25.988 CCM|DeviceManager:star_DeviceStart Name=SEP0016B64DDC0E Key=fba08135-5f60-1fdd-5041-2396367f14f8 RegisterDevice=15|< 09/09/2009 16:24:25.988 CCM|mMobileDevice = 0|< 09/09/2009 16:24:25.988 CCM|//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.2.200.195:[16352]: SIP/2.0 200 OK Date: Wed, 09 Sep 2009 14:24:25 GMT From: "Callmanager";tag=1047b60b Supported: X-cisco-sis-3.0.0 Content-Length: 0 To: "Callmanager";tag=1294152932 Contact: ;x-cisco-newreg Expires: 120 Call-ID: f733d630ff2b6a6dOGNhYzExZWJkOTJlZGE3ZjljOThhMzJiNjk5Y2Q2YTA. Via: SIP/2.0/UDP 10.2.200.195:16352;branch=z9hG4bK-d87543-5722e8026279962a-1--d87543-;rport CSeq: 2 REGISTER | 09/09/2009 16:24:25.989 CCM|EnvProcessUdpPort - EnvProcessUdpHandler::fireSignal() varId = 0| 09/09/2009 16:24:25.989 CCM|EnvProcessUdpPort - EnvProcessUdpHandler::send(buff, 497, 10.2.200.195:16352)| 09/09/2009 16:24:25.989 CCM|DeviceManager:star_DeviceStart Name=10030:6ce72bbf-94ab-aa2c-c527-8eea75b1da05 Key=ff24f82a-df94-f324-6714-d0cb8b5394c4 RegisterDevice=16|< 09/09/2009 16:24:25.989 CCM|mMobileDevice = 0|< 09/09/2009 16:24:25.989 CCM|SMDMSharedData::findRemoteDeviceList - Key=ff24f82a-df94-f324-6714-d0cb8b5394c4 not in RemoteDeviceInfo hashmap|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0x0/Searching for SCB in subID table with key 65277|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0x0/Added context(0xb41744a8) with key=[65041] to table|< 09/09/2009 16:24:25.989 CCM|//SIP/SIPHandler/ccbId=0/scbId=65277/ccsip_platform_init_scb: scb mgmt added 0xb41744a8|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0xb41744a8/Created new scb: 0xb41744a8 with id: 65277|< 09/09/2009 16:24:25.989 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=16352, isIPAddr=1, type=2|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 7 for event 204|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0x0/Event [SIPSPI_EV_CC_NOTIFY] received in State [SUBSCRIBE_STATE_IDLE]|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0xb41744a8/Inserting SCB with key 65277 into SubIDTable|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0x0/Created TCB: 0xb4172ed0|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0x0/TCB 0xb4172ed0's parent is 0xb41744a8|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Transport/0x0/Dialog Transaction Address 10.2.200.195,Port 16352, Port Present TRUE, Transport 1, FQDN 176607969|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0x0/Generated Subscribe Key : 78543800-aa71ba99-fbdd-ac8020a@10.2.200.10900040435|< 09/09/2009 16:24:25.989 CCM|//SIP/Stack/Info/0xb41744a8/Inserting SCB with key 78543800-aa71ba99-fbdd-ac8020a@10.2.200.10900040435 into ClientTable|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/TCB 0xb4172ed0's method is 111|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/TCB(0xb4172ed0) has claimed possesion of package (0xb418e498)|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/Client TCB's Marker Updated to: z9hG4bK28ee5537b5a2|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0xb41744a8/Entering ...|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/Created new edb(0xb417a140)|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/EDB updated with Event Name: message-summary Event ID: |< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0xb41744a8/Q'd EDB(0xb417a140) on SCB(0xb41744a8)|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0xb41744a8/There are 1 EDB's on edbQ|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/Q'd TCB(0xb4172ed0) on EDB(0xb417a140)|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0xb41744a8/There are 1 TCB's on tcbQ|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/Entering ...|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/Sending Package on tcb: 0xb4172ed0 and parent: 0xb41744a8 |< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0xb41744a8/msg=0xb418e498, addr=10.2.200.195, port=16352, sentBy_port=0, is_req=1, transport=1, switch=0, callBack=0x9c80a46|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0xb41744a8/Proceedable for sending msg immediately|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0xb41744a8/switch transport is 0|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0x0/Aging timer initiated for holder=0xb41f83a8,addr=10.2.200.195|< 09/09/2009 16:24:25.990 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_AGING value=840000 retries=0|< 09/09/2009 16:24:25.990 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_AGING value=840000 retries=0|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0x0/Created new holder=0xb41f83a8, addr=10.2.200.195; nailed=FALSE|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0x0/Posting UDP conn create request for addr=10.2.200.195, port=16352, context=0xb4106908|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0x0/Wait timer set for connection=0xb4107a60,addr=10.2.200.195, port=16352|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0x0/ Wait Conn Timer started for 5000 msec|< 09/09/2009 16:24:25.990 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0|< 09/09/2009 16:24:25.990 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0x0/Created new initiated conn=0xb4107a60, connid=-1, addr=10.2.200.195, port=16352, transport=UDP|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0x0/Registering gcb=0xb41744a8 with connection=0xb4107a60|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Transport/0xb41744a8/Deferred sending msg=0xb418e498|< 09/09/2009 16:24:25.990 CCM|//SIP/Stack/Info/0x0/Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_ACTIVE|< 09/09/2009 16:24:25.991 CCM|//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 57|< 09/09/2009 16:24:25.991 CCM|//SIP/Stack/Transport/0x0/context=0xb4106908|< 09/09/2009 16:24:25.991 CCM|//SIP/Stack/Transport/0x0/gConnTab=0xb4106908, addr=10.2.200.195, port=16352, connid=0, transport=UDP|< 09/09/2009 16:24:25.991 CCM|//SIP/Stack/Transport/0x0/Wait timer stopped for connection=0xb4107a60,addr=10.2.200.195, port=16352|< 09/09/2009 16:24:25.991 CCM|//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_WAIT_CONNECT value=5000 retries=0|< 09/09/2009 16:24:25.991 CCM|//SIP/Stack/Transport/0xb41744a8/Posting Internal Msg type=0|< 09/09/2009 16:24:25.991 CCM|//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 62|< 09/09/2009 16:24:25.991 CCM|//SIP/Stack/Transport/0x0/Posting send for msg=0xb418e498, addr=10.2.200.195, port=16352, connId=0 for UDP|< 09/09/2009 16:24:25.991 CCM|//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 10.2.200.195:[16352]: