06449001.001 |16:21:12.268 |AppInfo |//SIP/SIPUdp/wait_SdlDataInd: Incoming SIP UDP message size 531 from 192.168.208.30:[5060]: [9822,NET] REGISTER sip:192.168.208.20 SIP/2.0 Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac775955820 Max-Forwards: 70 From: ;tag=1c775948601 To: Call-ID: 319744902222016161531@192.168.208.30 CSeq: 11 REGISTER Contact: ;expires=180 Supported: path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Expires: 180 User-Agent: MP-114 FXS/v.6.60A.314.004 Content-Length: 0 06449002.000 |16:21:12.269 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,80,1) |SIPUdp(1,100,71,1) |1,100,10,1.2852^192.168.208.30^* |*TraceFlagOverrode 06449002.001 |16:21:12.269 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 1 (SIPSPI_EV_NEW_MESSAGE) 06449002.002 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWNewConnMsg: context=(nil) 06449002.003 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessNewConnMsg: gConnTab=0xd1d9d20, addr=192.168.208.30, port=5060, connid=5060, transport=UDP 06449002.004 |16:21:12.269 |AppInfo |//SIP/Stack/Info/0x0/sipHolderGetConnInstance: Return existing connection for port 5060 connId 5060 06449002.005 |16:21:12.269 |AppInfo |//SIP/Stack/Info/0x0/sipSPIFindRegisterCcb: *****CCB NOT found in UAS Request table. ccb=(nil) 06449002.006 |16:21:12.269 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xcfa4958) with key=[34903] to table 06449002.007 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Dialog Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449002.008 |16:21:12.269 |AppInfo |//SIP/Stack/States/0x0xcfa4958/sipSPIChangeState: 0xcfa4958 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 06449002.009 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449002.010 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449002.011 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449002.012 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPITransportSendMessage: msg=0xcc96bb0, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449002.013 |16:21:12.269 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipTransportPostSendMessage: Posting send for msg=0xcc96bb0, addr=192.168.208.30, port=5060, connId=0 for 06449002.014 |16:21:12.269 |AppInfo |//SIP/Stack/Info/0x0xcfa4958/sipSPIUaddCcbToTable: Added to table. ccb=0xcfa4958 key=319744902222016161531@192.168.208.3010021c775948601 06449002.015 |16:21:12.269 |AppInfo |//SIP/SIPHandler/ccbId=34915/scbId=0/findDevicePID: Routed to SIPStationInit 06449002.016 |16:21:12.269 |AppInfo |//SIP/SIPHandler/ccbId=34915/scbId=0/ccsip_api_register_ind: ++++++ Incrememted gSipNewRegPendingNum to 1 06449002.017 |16:21:12.269 |AppInfo |//SIP/Stack/Event/0x0xcfa4958/sact_idle_new_message_register: ccsip_api_register_ind return value : SIP_SUCCESS 06449002.018 |16:21:12.269 |AppInfo |//SIP/Stack/States/0x0xcfa4958/sipSPIChangeState: 0xcfa4958 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) 06449003.000 |16:21:12.269 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2852^192.168.208.30^* |*TraceFlagOverrode 06449003.001 |16:21:12.269 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9823,NET] SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac775955820 From: ;tag=1c775948601 To: Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319744902222016161531@192.168.208.30 CSeq: 11 REGISTER Content-Length: 0 06449004.000 |16:21:12.269 |SdlSig |SIPRegisterInd |wait |SIPStationInit(1,100,73,1) |SIPHandler(1,100,80,1) |1,100,10,1.2852^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34915 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449004.001 |16:21:12.269 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449004.002 |16:21:12.269 |AppInfo |SIPStationInit: connId=0, 1002@192.168.208.30:5060, New 3rd-party (RFC3261) phone registering 06449004.003 |16:21:12.269 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, New targetEntry created 06449004.004 |16:21:12.269 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, (RegTrace 0xe5578770) Enqueuing register for ccbID 34915 06449004.005 |16:21:12.269 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, REGISTER Queued 06449004.006 |16:21:12.269 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, DevStat-InitState: NEW --> ON_NEWREG_QUEUE 06449005.000 |16:21:12.277 |SdlSig |SdlDataInd |wait |SIPUdp(1,100,71,1) |SdlUDPConnection(1,100,10,1) |1,100,10,1.2853^192.168.208.30^* |*TraceFlagOverrode 06449005.001 |16:21:12.277 |AppInfo |//SIP/SIPUdp/wait_SdlDataInd: Incoming SIP UDP message size 530 from 192.168.208.30:[5060]: [9824,NET] REGISTER sip:192.168.208.20 SIP/2.0 Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac775975264 Max-Forwards: 70 From: ;tag=1c775970002 To: Call-ID: 319790537222016161531@192.168.208.30 CSeq: 8 REGISTER Contact: ;expires=180 Supported: path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Expires: 180 User-Agent: MP-114 FXS/v.6.60A.314.004 Content-Length: 0 06449006.000 |16:21:12.277 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,80,1) |SIPUdp(1,100,71,1) |1,100,10,1.2853^192.168.208.30^* |*TraceFlagOverrode 06449006.001 |16:21:12.277 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 1 (SIPSPI_EV_NEW_MESSAGE) 06449006.002 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWNewConnMsg: context=(nil) 06449006.003 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessNewConnMsg: gConnTab=0xd1d9d20, addr=192.168.208.30, port=5060, connid=5060, transport=UDP 06449006.004 |16:21:12.277 |AppInfo |//SIP/Stack/Info/0x0/sipHolderGetConnInstance: Return existing connection for port 5060 connId 5060 06449006.005 |16:21:12.277 |AppInfo |//SIP/Stack/Info/0x0/sipSPIFindRegisterCcb: *****CCB NOT found in UAS Request table. ccb=(nil) 06449006.006 |16:21:12.277 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xcfa90e8) with key=[34904] to table 06449006.007 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Dialog Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449006.008 |16:21:12.277 |AppInfo |//SIP/Stack/States/0x0xcfa90e8/sipSPIChangeState: 0xcfa90e8 : State change from (STATE_NONE, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 06449006.009 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449006.010 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449006.011 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449006.012 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPITransportSendMessage: msg=0xcfa8dd8, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449006.013 |16:21:12.277 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipTransportPostSendMessage: Posting send for msg=0xcfa8dd8, addr=192.168.208.30, port=5060, connId=0 for 06449006.014 |16:21:12.277 |AppInfo |//SIP/Stack/Info/0x0xcfa90e8/sipSPIUaddCcbToTable: Added to table. ccb=0xcfa90e8 key=319790537222016161531@192.168.208.3010011c775970002 06449006.015 |16:21:12.277 |AppInfo |//SIP/SIPHandler/ccbId=34916/scbId=0/findDevicePID: Routed to SIPStationInit 06449006.016 |16:21:12.277 |AppInfo |//SIP/SIPHandler/ccbId=34916/scbId=0/ccsip_api_register_ind: ++++++ Incrememted gSipNewRegPendingNum to 2 06449006.017 |16:21:12.277 |AppInfo |//SIP/Stack/Event/0x0xcfa90e8/sact_idle_new_message_register: ccsip_api_register_ind return value : SIP_SUCCESS 06449006.018 |16:21:12.277 |AppInfo |//SIP/Stack/States/0x0xcfa90e8/sipSPIChangeState: 0xcfa90e8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) 06449007.000 |16:21:12.277 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2853^192.168.208.30^* |*TraceFlagOverrode 06449007.001 |16:21:12.277 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9825,NET] SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac775975264 From: ;tag=1c775970002 To: Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319790537222016161531@192.168.208.30 CSeq: 8 REGISTER Content-Length: 0 06449008.000 |16:21:12.277 |SdlSig |SIPRegisterInd |wait |SIPStationInit(1,100,73,1) |SIPHandler(1,100,80,1) |1,100,10,1.2853^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34916 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449008.001 |16:21:12.277 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449008.002 |16:21:12.277 |AppInfo |SIPStationInit: connId=0, , 1001@192.168.208.30:5060, state=ON_NEWREG_QUEUE, 3rd-party (RFC-3261) phone found by contact 06449008.003 |16:21:12.277 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, (RegTrace 0xe5578770) Enqueuing register for ccbID 34916 06449008.004 |16:21:12.277 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, REGISTER Queued 06449008.005 |16:21:12.277 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, DevStat-InitState: ON_NEWREG_QUEUE --> ON_NEWREG_QUEUE 06449009.000 |16:21:12.382 |SdlSig |ServiceSIPRegistrationQueueTimer |wait |SIPStationInit(1,100,73,1) |SdlTimerService(1,100,3,1) |1,100,10,1.2852^192.168.208.30^* |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0] 06449009.001 |16:21:12.382 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, Creating new SIPStationD 06449009.002 |16:21:12.382 |Created | | |SIPStationD(1,100,74,106) |SIPStationInit(1,100,73,1) | |NumOfCurrentInstances: 1 06449009.003 |16:21:12.382 |AppInfo |SIPRegistrationTable::pidAdd: Adding pid to table via pidAdd [1,100,74,106]: 06449009.004 |16:21:12.382 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, DevStat-InitState: ON_NEWREG_QUEUE --> REGISTERED 06449009.005 |16:21:12.382 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, SIPStationD PID for entry exists, forwarding REGISTER 06449009.006 |16:21:12.382 |AppInfo |SIPStationInit: RegQueueSize=0 Qlimit=250 MaxEntriesToProcess=90 tokeMax=10 tokeOut=0 Registration Queue Serviced 2 entries in 0 milliseconds. gSipNewRegPendingNum: 2 06449010.000 |16:21:12.382 |SdlSig |Start |start |SIPStationD(1,100,74,106) |SIPStationD(1,100,74,106) |1,100,10,1.2852^192.168.208.30^* |*TraceFlagOverrode 06449010.001 |16:21:12.382 |AppInfo |SIPStationD(106) - Primary expires 120, secondary expires 3600 06449011.000 |16:21:12.383 |SdlSig |SIPRegisterInd |wait_register |SIPStationD(1,100,74,106) |SIPStationInit(1,100,73,1) |1,100,10,1.2852^192.168.208.30^* |[R:V-H:0,N:0,L:0,V:1,Z:0,D:0] ccbID= 34915 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449011.001 |16:21:12.383 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449011.002 |16:21:12.383 |AppInfo |SIPStationD(106) - userStart chars . 2 0 06449011.003 |16:21:12.383 |AppInfo |SIPStationD(106) - model=255, mUaType=0 06449011.004 |16:21:12.383 |AppInfo |SIPStationD(106) - Register instanceId (MAC addr) =NULL 06449011.005 |16:21:12.383 |AppInfo |SIPStationD(106) - Register deviceName (database key) =NULL 06449011.006 |16:21:12.383 |AppInfo |SIPStationD(106) - No instance ID or digest-auth, sending challenge. 06449011.007 |16:21:12.383 |AppInfo |EndPointTransientConnection - An endpoint attempted to register but did not complete registration Connecting Port:5060 Device name: Device type:336 Reason Code:20 Protocol:SIP LastSignalReceived:SIPRegisterInd StationState:wait_register App ID:Cisco CallManager Cluster ID:StandAloneCluster Node ID:cucm1105 06449011.008 |16:21:12.383 |AlarmErr |AlarmClass: CallManager, AlarmName: EndPointTransientConnection, AlarmSeverity: Error, AlarmMessage: , AlarmDescription: An endpoint attempted to register but did not complete registration, AlarmParameters: ConnectingPort:5060, DeviceName:, DeviceType:336, Reason:20, Protocol:SIP, LastSignalReceived:SIPRegisterInd, StationState:wait_register, AppID:Cisco CallManager, ClusterID:StandAloneCluster, NodeID:cucm1105, 06449011.009 |16:21:12.385 |AppInfo |SIPStationD(106) - it's first challenge . Set Challenge timeout timer 06449012.000 |16:21:12.385 |SdlSig |SIPRegisterInd |wait_register |SIPStationD(1,100,74,106) |SIPStationInit(1,100,73,1) |1,100,10,1.2852^192.168.208.30^* |[R:V-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34916 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449012.001 |16:21:12.385 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449012.002 |16:21:12.385 |AppInfo |SIPStationD(106) - userStart chars . 2 0 06449012.003 |16:21:12.385 |AppInfo |SIPStationD(106) - model=255, mUaType=0 06449012.004 |16:21:12.385 |AppInfo |SIPStationD(106) - Register instanceId (MAC addr) =NULL 06449012.005 |16:21:12.385 |AppInfo |SIPStationD(106) - Register deviceName (database key) =NULL 06449012.006 |16:21:12.385 |AppInfo |SIPStationD(106) - No instance ID or digest-auth, sending challenge. 06449013.000 |16:21:12.385 |SdlSig |SIPRegisterResp |wait |SIPHandler(1,100,80,1) |SIPStationD(1,100,74,106) |1,100,10,1.2852^192.168.208.30^* |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0] ccbID= 34915 --TransType=2 --TransSecurity=0 PeerAddr= 192.168.208.30:5060 respCode= 401 action= 2 device= 06449013.001 |16:21:12.385 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPRegisterResp: ++++++ Decrememted gSipNewRegPendingNum to 1 06449013.002 |16:21:12.385 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 (SIP_APPLICATION_MSG), for event 43 (SIPSPI_EV_REGISTER_RESP) 06449013.003 |16:21:12.385 |AppInfo |//SIP/Stack/Info/0x0xcfa4958/sipSPISendResponse: Associated container=0xe555b030 to REGISTER Response 401 06449013.004 |16:21:12.385 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449013.005 |16:21:12.385 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPITransportSendMessage: msg=0xcfa8dd8, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449013.006 |16:21:12.385 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipTransportPostSendMessage: Posting send for msg=0xcfa8dd8, addr=192.168.208.30, port=5060, connId=0 for 06449013.007 |16:21:12.385 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: timerContext=0xcfa5938 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449013.008 |16:21:12.385 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: timerContext=0xcfa5938 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449013.009 |16:21:12.385 |AppInfo |//SIP/Stack/States/0x0xcfa4958/sipSPIChangeState: 0xcfa4958 : State change from (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 06449014.000 |16:21:12.385 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2852^192.168.208.30^* |*TraceFlagOverrode 06449014.001 |16:21:12.385 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9826,NET] SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac775955820 From: ;tag=1c775948601 To: ;tag=1789228510 Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319744902222016161531@192.168.208.30 CSeq: 11 REGISTER WWW-Authenticate: Digest realm="ccmsipline", nonce="PV+WTHE8JXEsyUdhlG411pBOUZ4zDE2R", algorithm=MD5 Content-Length: 0 06449015.000 |16:21:12.385 |SdlSig |SIPRegisterResp |wait |SIPHandler(1,100,80,1) |SIPStationD(1,100,74,106) |1,100,10,1.2852^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34916 --TransType=2 --TransSecurity=0 PeerAddr= 192.168.208.30:5060 respCode= 401 action= 2 device= 06449015.001 |16:21:12.385 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPRegisterResp: ++++++ Decrememted gSipNewRegPendingNum to 0 06449015.002 |16:21:12.385 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 (SIP_APPLICATION_MSG), for event 43 (SIPSPI_EV_REGISTER_RESP) 06449015.003 |16:21:12.385 |AppInfo |//SIP/Stack/Info/0x0xcfa90e8/sipSPISendResponse: Associated container=0xe555bec0 to REGISTER Response 401 06449015.004 |16:21:12.385 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449015.005 |16:21:12.385 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPITransportSendMessage: msg=0xcfa8dd8, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449015.006 |16:21:12.385 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipTransportPostSendMessage: Posting send for msg=0xcfa8dd8, addr=192.168.208.30, port=5060, connId=0 for 06449015.007 |16:21:12.386 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: timerContext=0xcfaa0c8 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449015.008 |16:21:12.386 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: timerContext=0xcfaa0c8 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449015.009 |16:21:12.386 |AppInfo |//SIP/Stack/States/0x0xcfa90e8/sipSPIChangeState: 0xcfa90e8 : State change from (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 06449016.000 |16:21:12.386 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2852^192.168.208.30^* |*TraceFlagOverrode 06449016.001 |16:21:12.386 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9827,NET] SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac775975264 From: ;tag=1c775970002 To: ;tag=1573211610 Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319790537222016161531@192.168.208.30 CSeq: 8 REGISTER WWW-Authenticate: Digest realm="ccmsipline", nonce="PV+WTHE8JXEsyUdhlG411pBOUZ4zDE2R", algorithm=MD5 Content-Length: 0 06449017.000 |16:21:12.420 |SdlSig |SdlDataInd |wait |SIPUdp(1,100,71,1) |SdlUDPConnection(1,100,10,1) |1,100,10,1.2854^192.168.208.30^* |*TraceFlagOverrode 06449017.001 |16:21:12.420 |AppInfo |//SIP/SIPUdp/wait_SdlDataInd: Incoming SIP UDP message size 713 from 192.168.208.30:[5060]: [9828,NET] REGISTER sip:192.168.208.20 SIP/2.0 Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac776337481 Max-Forwards: 70 From: ;tag=1c775948601 To: Call-ID: 319744902222016161531@192.168.208.30 CSeq: 12 REGISTER Contact: ;expires=180 Authorization: Digest username="1002",realm="ccmsipline",nonce="PV+WTHE8JXEsyUdhlG411pBOUZ4zDE2R",uri="sip:192.168.208.20",algorithm=MD5,response="9a51c4ee348e5279ae58cba4948a946d" Supported: path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Expires: 180 User-Agent: MP-114 FXS/v.6.60A.314.004 Content-Length: 0 06449018.000 |16:21:12.421 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,80,1) |SIPUdp(1,100,71,1) |1,100,10,1.2854^192.168.208.30^* |*TraceFlagOverrode 06449018.001 |16:21:12.421 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 1 (SIPSPI_EV_NEW_MESSAGE) 06449018.002 |16:21:12.421 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWNewConnMsg: context=(nil) 06449018.003 |16:21:12.421 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessNewConnMsg: gConnTab=0xd1d9d20, addr=192.168.208.30, port=5060, connid=5060, transport=UDP 06449018.004 |16:21:12.421 |AppInfo |//SIP/Stack/Info/0x0/sipHolderGetConnInstance: Return existing connection for port 5060 connId 5060 06449018.005 |16:21:12.421 |AppInfo |//SIP/Stack/Info/0x0xcfa4958/sipSPIFindRegisterCcb: *****CCB found in UAS Request table. ccb=0xcfa4958 06449018.006 |16:21:12.421 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449018.007 |16:21:12.421 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449018.008 |16:21:12.421 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449018.009 |16:21:12.421 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPITransportSendMessage: msg=0xcfa8dd8, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449018.010 |16:21:12.421 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipTransportPostSendMessage: Posting send for msg=0xcfa8dd8, addr=192.168.208.30, port=5060, connId=0 for 06449018.011 |16:21:12.421 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: timerContext=0xcfa5938 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449018.012 |16:21:12.421 |AppInfo |//SIP/SIPHandler/ccbId=34915/scbId=0/findDevicePID: Routed to SIPStationInit 06449018.013 |16:21:12.421 |AppInfo |//SIP/SIPHandler/ccbId=34915/scbId=0/ccsip_api_register_ind: ++++++ Incrememted gSipNewRegPendingNum to 1 06449018.014 |16:21:12.421 |AppInfo |//SIP/Stack/Event/0x0xcfa4958/sact_idle_new_message_register: ccsip_api_register_ind return value : SIP_SUCCESS 06449018.015 |16:21:12.421 |AppInfo |//SIP/Stack/States/0x0xcfa4958/sipSPIChangeState: 0xcfa4958 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) 06449019.000 |16:21:12.421 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2854^192.168.208.30^* |*TraceFlagOverrode 06449019.001 |16:21:12.421 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9829,NET] SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac776337481 From: ;tag=1c775948601 To: ;tag=1789228510 Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319744902222016161531@192.168.208.30 CSeq: 12 REGISTER Content-Length: 0 06449020.000 |16:21:12.421 |SdlSig |SIPRegisterInd |wait |SIPStationInit(1,100,73,1) |SIPHandler(1,100,80,1) |1,100,10,1.2854^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34915 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449020.001 |16:21:12.421 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449020.002 |16:21:12.421 |AppInfo |SIPStationInit: connId=0, , 1002@192.168.208.30:5060, state=REGISTERED, 3rd-party (RFC-3261) phone found by contact 06449020.003 |16:21:12.421 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, Routing to D (1,100,74,106), regCount=3 06449021.000 |16:21:12.421 |SdlSig |SIPRegisterInd |wait_register |SIPStationD(1,100,74,106) |SIPStationInit(1,100,73,1) |1,100,10,1.2854^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34915 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449021.001 |16:21:12.421 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449021.002 |16:21:12.421 |AppInfo |SIPStationD(106) - userStart chars . 2 0 06449021.003 |16:21:12.421 |AppInfo |SIPStationD(106) - model=255, mUaType=0 06449021.004 |16:21:12.421 |AppInfo |SIPStationD(106) - Register instanceId (MAC addr) =NULL 06449021.005 |16:21:12.421 |AppInfo |SIPStationD(106) - Register deviceName (database key) =NULL 06449021.006 |16:21:12.421 |AppInfo |SIPStationD(106) - DevStat-Start : transport UDP, model 336 06449021.007 |16:21:12.421 |AppInfo |SIPStationD(106) - parseSupportedHeader: X-cisco-callinfo=F X-cisco-serviceuri=F X-cisco-escapecodes=F X-cisco-service-control=F X-cisco-duplicate-reg=F X-cisco-graceful-reg=F X-cisco-srtp-fallback=F X-cisco-ix=F X-cisco-monrec=F X-cisco-xsi=F xsi-version=0.0.0 X-cisco-sis=F sis-version=0.0.0 extended-refer=F norefersub=F join=F cso[rcc=F,sc=F,max=0] slNotify=0 hsNotify=0 blfNotify=0 fi[joinReq=F,cfwdAny=F,coaching=F,oosAlarm=F,joinDXferPolicy=2,xCiscoNumber=F,OrigCalled=F,iXChannel=F,BFCP=F,mobileConnectStatusRefresh=F,gatewayRec=F,qosTcl=F,confDisplayInstanceSupport=F]MobilityExtension Feature Indication is empty 06449021.008 |16:21:12.421 |AppInfo |SIPStationD(106) - Convert UserId "1002" to deviceName 06449021.009 |16:21:12.421 |AppInfo |SIPStationD(106) - deviceType=336, mSIPAuthorizationHeaderPtr->user_pass=1002 06449021.010 |16:21:12.422 |AppInfo |CcmCcmdbHelper::getProcessConfigGivenProcessnodeParamnameService - Value of DeviceMobilityMode from process config table is (0) 06449021.011 |16:21:12.422 |AppInfo |CcmCcmdbHelper::getProcessConfigGivenProcessnodeParamnameService - Value of DeviceMobilityMode from process config table is (0) 06449021.012 |16:21:12.422 |AppInfo |GetMaxCallForDevice: cannot find entry in ProductSupportsFeature table for product 273 and protocol 11feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS 06449021.013 |16:21:12.422 |AppInfo |GetMaxCallForDevice: cannot find entry in ProductSupportsFeature table for product 273 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS 06449021.014 |16:21:12.422 |AppInfo |$URI CcmdbStationRegistrationProfileBuilder::getSipExtensionByDeviceName: Disambiguation Policy - deviceName=SEPDEADBEAF0002, sipprofile.name=Standard SIP Profile, sipprofile.tkuridisambiguationpolicy_m=URIDISAMBIGUATIONPOLICY_DNIFONLYDIGITSTARPLUS [4] 06449021.015 |16:21:12.422 |AppInfo |SIPStationD(106) - Setting nonce refresh timer 600 sec 06449021.016 |16:21:12.422 |AppInfo |CCMEncryption::hexToPassword():enter 06449021.017 |16:21:12.422 |AppInfo |CCMEncryption::hexToPassword():exit 06449021.018 |16:21:12.422 |AppInfo | SIPSecurity::decrypt hex password = "311aa2b38448beeb99b264debd744f7c7d8fc9c62f70dfd409873fdb55e643f0" , ecnrypted len 32 06449021.019 |16:21:12.422 |AppInfo |CCMEncryption::DecryptText:enter 06449021.020 |16:21:12.422 |AppInfo |CCMEncryption::DecryptText (Exit) (Success)) 06449021.021 |16:21:12.423 |AppInfo |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 374 and protocol 11 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH 06449021.022 |16:21:12.423 |AppInfo |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 374 and protocol 99 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH 06449021.023 |16:21:12.423 |AppInfo |SIPSecurity::verifyAuthResponse: cred.username=1002, cred.password=5678, auth_hdr.realm=ccmsipline, aut_hdr.nonce=PV+WTHE8JXEsyUdhlG411pBOUZ4zDE2R 06449021.024 |16:21:12.423 |AppInfo |SIPStationD(106) - Digest authentication success (db)realm:ccmsipline (db)user:1002 (db)device:SEPDEADBEAF0002 (db)maxAuthnameLen:128 06449021.025 |16:21:12.423 |AppInfo |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 374 and protocol 11 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH 06449021.026 |16:21:12.423 |AppInfo |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 374 and protocol 99 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH 06449021.027 |16:21:12.423 |AppInfo |SIPStationD(106) - importConfig: IPV6 SP not enabled setting IP Address Mode to v4 0 06449021.028 |16:21:12.423 |AppInfo |getMaxCallForDeviceFromModel: ProductSupportsFeature table entry for model 374 and feature SUPPORTS_FEATURE_MULTIPLE_CALL_DISPLAY has deviceprotocol = 11 maxcalls = 16 06449021.029 |16:21:12.423 |AppInfo |SIPStationD(106) - trustedDevice == 0, deviceSecurityMode == 0, clusterSecurityMode == 0, exp = 3/1 06449021.030 |16:21:12.423 |AppInfo |SIPStationD(106) - VerFeatMask=0x00000000, hasCrypto=0 06449021.031 |16:21:12.423 |AppInfo |SIPStationD(106) - mtpRequired = 0 useTRP = 0 mtpPrefOrgCodec = 1 unAttendedPort = 0 earlyOfferSupport = 0 requireDTMFReception = 0 packetCaptureMode = 0 packetCaptureDuration = 0 06449021.032 |16:21:12.423 |AppInfo |SIPStationD(106) - securityProfileName = SIP Default with Diget Auth, nonceValidityTime = 600 transportTypeValue = 4 digestAuthorization = 1 06449021.033 |16:21:12.423 |AppInfo |SIPStationD(106) - deviceSecurityMode = 0 06449021.034 |16:21:12.423 |AppInfo |SIPStationD(106) - subscribeCSS = css_reroute = css_ood = presenceGroup = ad243d17-98b4-4118-8feb-5ff2e1b781ac dialRules = 06449021.035 |16:21:12.423 |AppInfo |SIPStationD(106) - versionStamp = 1454419215-ba1256a4-96a8-45a6-9b8b-361637729ecd, softkeyTemplateVersionStamp = , dialRulesVersionStamp = , featureControlVersionStamp = 0000000000-c7a6c673-7479-46b0-839e-014d3d093963 06449021.036 |16:21:12.423 |AppInfo |SIPStationD(106) - SIPDigestUserEntry: userName = 1002 credentials = 311aa2b38448beeb99b264debd744f7c7d8fc9c62f70dfd409873fdb55e643f0 SIPDevice = SEPDEADBEAF0002 enduserPresenceGroup = 06449021.037 |16:21:12.423 |AppInfo |SIPStationD(106) - SIPProfile: SIPProfileInfoName = Standard SIP Profile rerouteType = 0 timeInviteExpires = 180 timerRegDelta = 5 timerRegExpires = 3600 06449021.038 |16:21:12.423 |AppInfo |SIPStationD(106) - SIPProfile: timerT1 = 500, timerT2 = 4000, retryInvite = 6, tryOnInvite = 10, startMediaPort = 16384, ringing180 = 0, defaultTelephonyEvent = 101, honorIncomingCodecPref = 2 06449021.039 |16:21:12.423 |AppInfo |SIPStationD(106) - callPickupURI = [x-cisco-serviceuri-pickup] callPickupGroupURI = [x-cisco-serviceuri-gpickup] 06449021.040 |16:21:12.423 |AppInfo |SIPStationD(106) - callPickupOtherGroupURI = [x-cisco-serviceuri-opickup] meetMeURI = [x-cisco-serviceuri-meetme] 06449021.041 |16:21:12.423 |AppInfo |SIPStationD(106) - callForwardURI = [x-cisco-serviceuri-cfwdall] abbrdialURI = [x-cisco-serviceuri-abbrdial] 06449021.042 |16:21:12.423 |AppInfo |SIPStationD(106) - blfpickupURI = [x-cisco-serviceuri-blfpickup] 06449021.043 |16:21:12.423 |AppInfo |SIPStationD(106) - Device Mobility: MobileDevice = false DevicePoolPkid = 150af0ad-d875-2d22-2d16-b2c981cdf3b0 RoamingDevicePoolPkid = PhysicalLocation = AARNeighborHood = 06449021.044 |16:21:12.423 |AppInfo |CcmCcmdbHelper::setRecordingInfo - Preffered recordingMethod = 1 06449021.045 |16:21:12.423 |AppInfo |SIPStationD(106) - initLineStruct: Initializing index 1, DN 1002 06449021.046 |16:21:12.423 |AppInfo |SIPStationD(106) - initLineStruct: MonRec data: recType=0, recDN=, recCSS=, monCSS= 06449021.047 |16:21:12.423 |AppInfo |setIsWildcardDN: digits 1002, isWildcardDN false 06449021.048 |16:21:12.423 |AppInfo |SIPStationD(106) - isPrivacyButtonConfigured:false isPrivacyOn:true 06449021.049 |16:21:12.423 |AppInfo |SIPStationD(106) - wait_register_SIPRegisterInd: save mSspIpAddrMode 0 06449021.050 |16:21:12.423 |AppInfo |SIPStationD(106) - setIPAddr: Registering phone IP: 192.168.208.30 06449021.051 |16:21:12.423 |AppInfo |SIPStationD(106) - setIPAddr: ipAddrType 0, mDeviceProperty.ipAddrMode 0, mSspIpAddrMode 0 06449021.052 |16:21:12.423 |AppInfo |SIPStationD(106) - checkDNsReceived: TotalCount=1 06449021.053 |16:21:12.423 |AppInfo |SIPStationD(106) - sendLineRegisterReq: mLineRegisterReqsOutstanding=1 06449021.054 |16:21:12.423 |AppInfo |SIPStationD(106) - Device pkid-- 54ee2709-0acf-4758-62be-1a0f7e73e292 06449021.055 |16:21:12.424 |AppInfo |SIPStationD(106) - transport UDP security None, model 336 06449021.056 |16:21:12.424 |AppInfo |SIPStationD(106) - setOptionsIndicationDefaultOptions: INFO - user agent 5 06449021.057 |16:21:12.424 |AppInfo |SIPStationD(106) - wait_register_SIPRegisterInd Supports Original Called 0 06449021.058 |16:21:12.424 |AppInfo |SIPStationD(106) - wait_register_SIPRegisterInd Supports bfcp 0 06449021.059 |16:21:12.424 |AppInfo |SIPStationD(106) - wait_register_SIPRegisterInd phone Supports Gateway Recording 0 06449021.060 |16:21:12.424 |AppInfo |SIPStationD(106) - CCMTCL wait_register_SIPRegisterInd REGISTER supports qos-tcl 0 06449022.000 |16:21:12.424 |SdlSig |DbObjectCacheTimer |initialized |Db(1,100,211,1) |SdlTimerService(1,100,3,1) |1,100,148,1.1^*^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] AppCorr: 0 06449023.000 |16:21:12.424 |SdlSig |LineRegisterReq |wait |LineManager(1,100,175,1) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* |[R:L-H:0,N:0,L:0,V:0,Z:0,D:0] Partition=8d14c5a4-8a54-20be-c902-fd7c302135d2Pattern=1002DevicePID=(1,74,106) mLine=1 06449024.000 |16:21:12.424 |SdlSig |DmPidReq |initialized |DeviceManager(1,100,208,1) |LineManager(1,100,175,1) |1,100,10,1.2854^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] Cepn=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 Id=0 06449024.001 |16:21:12.424 |AppInfo |SMDMSharedData::findAliasRegInfo - AliasName = e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 not in AliasInfo hashmap 06449024.002 |16:21:12.424 |AppInfo |DeviceManager::star_DmPidReq - RequestedName=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 LookupName=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 06449024.003 |16:21:12.424 |AppInfo |SMDMSharedData::findRemoteDeviceAny - Key=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 not in RemoteDeviceInfo hashmap 06449025.000 |16:21:12.424 |SdlSig |DmPidErr |wait_dm_pid_res |LineManager(1,100,175,1) |DeviceManager(1,100,208,1) |1,100,10,1.2854^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] Cepn=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 Id=0 Pid=0,0,0,0 06449025.001 |16:21:12.424 |Created | | |LineControl(1,100,176,82) |LineManager(1,100,175,1) | |NumOfCurrentInstances: 2 06449026.000 |16:21:12.424 |SdlSig |LineRegisterRes |line_register |SIPStationD(1,100,74,106) |LineManager(1,100,175,1) |1,100,10,1.2854^192.168.208.30^* |[R:L-H:0,N:0,L:0,V:0,Z:0,D:0] Partition=8d14c5a4-8a54-20be-c902-fd7c302135d2Pattern=1002varPID=(1,176,82) mLine=1 06449026.001 |16:21:12.424 |AppInfo |SIPStationD(106) - line_register_LineRegisterRes: mLineRegisterReqsOutstanding=0 06449026.002 |16:21:12.424 |AppInfo |SIPStationD(106) - storeContactHeader: Request-URI is sip:1002@192.168.208.30:5060;user=phone 06449026.003 |16:21:12.424 |AppInfo |setSIPAoR: aor = sip:1002@192.168.208.20;user=phone 06449026.004 |16:21:12.424 |AppInfo |setIsWildcardDN: digits 1002, isWildcardDN false 06449026.005 |16:21:12.424 |AppInfo |SIPStationD(106) - Started 1 second partially-registered alarm timer. 06449026.006 |16:21:12.424 |AppInfo |SIPStationD(106) - completeDeviceRegistration - mTempCOntact 06449026.007 |16:21:12.424 |AppInfo |SIPStationD(106) - checkForVideoParameterInRegister- tempContact = 06449026.008 |16:21:12.424 |AppInfo |SIPStationD(106) - checkForVideoParameterInRegister - device SEPDEADBEAF0002 Old 0 new 0 06449026.009 |16:21:12.424 |AppInfo |SIPStationD(106) - Leaving checkForVideoParameterInRegister - device SEPDEADBEAF0002 video 0 Number of Screens = 0 tip 0 06449026.010 |16:21:12.424 |AppInfo |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 374 and protocol 0feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS 06449026.011 |16:21:12.424 |AppInfo |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 374 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS 06449026.012 |16:21:12.424 |AppInfo |SIPStationD(106) - DevStat-NewState : line 1002: Null ==> Registered 06449026.013 |16:21:12.424 |AppInfo |SIPStationD(106) - Stopped partially-registered alarm timer. 06449026.014 |16:21:12.425 |AppInfo |SIPStationD(106) - sendDeviceRegisteredAlarm: Raised Device Registered Alarm with Serviceability 06449026.015 |16:21:12.425 |AppInfo |SIPStationD(106) - VerFeatMask=0x00000000, hasCrypto=0 06449026.016 |16:21:12.425 |AppInfo |StationCtiUtils::GetLoginStatus profilePkid is EMPTY 06449026.017 |16:21:12.425 |AppInfo |StationCtiUtils::GetLoginStatus Incorrect EM Info 06449026.018 |16:21:12.425 |AppInfo |StationCtiUtils::GetLoginStatus profilePkid is EMPTY 06449026.019 |16:21:12.425 |AppInfo |StationCtiUtils::GetLoginStatus Incorrect EM Info 06449026.020 |16:21:12.425 |AppInfo |StationCtiD::getDeviceAndLineInfo featureID=9 06449026.021 |16:21:12.425 |AppInfo |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 : : 06449026.022 |16:21:12.425 |AppInfo |StationCtiD::getDeviceAndLineInfo PickupDN= 06449026.023 |16:21:12.425 |AppInfo |StationCtiD::getDeviceAndLineInfo PickupPartition= 06449026.024 |16:21:12.425 |AppInfo |SIPStationD(106) - buildCFwdAll: forwardAllDirNum[], forwardAllVoiceMailOnOff[off] 06449026.025 |16:21:12.425 |AppInfo |SIPStationD(106) - sendDeviceStatusInformation: hlog status: 1 06449026.026 |16:21:12.425 |AppInfo |SIPStationD(106) - sendDeviceStatusInformation: privacy status: 1 06449026.027 |16:21:12.425 |AppInfo |SIPStationD(106) - Phone does not support service-control events, not sending NOTIFY 06449026.028 |16:21:12.425 |AppInfo |SIPStationD(106) - buildServiceControlNotifyReq: Error building service-control NOTIFY 06449026.029 |16:21:12.425 |AppInfo |SIPStationD(106) - sendServiceControlNotify: failed to send service-control NOTIFY for action=3 06449027.000 |16:21:12.425 |SdlSig |SIPRegisterResp |wait |SIPHandler(1,100,80,1) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34915 --TransType=2 --TransSecurity=0 PeerAddr= 192.168.208.30:5060 respCode= 200 action= 1 device= SEPDEADBEAF0002 06449027.001 |16:21:12.425 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPRegisterResp: ++++++ Decrememted gSipNewRegPendingNum to 0 06449027.002 |16:21:12.425 |AppInfo |getScriptPKIDFromSIPProfile: script PKID is trace: 0 param 06449027.003 |16:21:12.425 |AppInfo |getScriptNameFromSIPProfile: failed to get script name given pkid 06449027.004 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 (SIP_APPLICATION_MSG), for event 43 (SIPSPI_EV_REGISTER_RESP) 06449027.005 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0xcfa4958/sipSPIPushSubsqTransMsgContainerIntoHol: Response Container Holder is above threshold...tri 06449027.006 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0xcfa4958/sipSPISendResponse: Associated container=0xe62fb6f0 to REGISTER Response 200 06449027.007 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449027.008 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipSPITransportSendMessage: msg=0xcfc15d8, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449027.009 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0xcfa4958/sipTransportPostSendMessage: Posting send for msg=0xcfc15d8, addr=192.168.208.30, port=5060, connId=0 for 06449027.010 |16:21:12.425 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: timerContext=0xcfa5938 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449027.011 |16:21:12.425 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: timerContext=0xcfa5938 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449027.012 |16:21:12.425 |AppInfo |//SIP/Stack/States/0x0xcfa4958/sipSPIChangeState: 0xcfa4958 : State change from (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 06449028.000 |16:21:12.425 |SdlSig |LmFeatureInd |initialize |LineControl(1,100,176,82) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* |[R:N-H:0,N:3,L:2,V:0,Z:0,D:0] CI=0 feature=1 featureState=1 dndOption=0 06449029.000 |16:21:12.425 |SdlSig-S |LmFeatureInd |initialize |LineControl(1,100,176,82) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* | 06449030.000 |16:21:12.425 |SdlSig |CtiDeviceRegisterNotifyWithLineInfo |wait |CTIRegistrar(1,100,233,1) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* |[R:N-H:0,N:2,L:2,V:0,Z:0,D:0] deviceName=SEPDEADBEAF0002 EventContent=14 DeviceConfigInfo= Locale=1 AltScrpt= DNDOption=0 ConfigIpAddrMode=3 Hotelling=F Restricted=T OutboundRO=0 BIB=F DNDStatus=F LoginStatus=0 DeviceRegistrationInfo= TerminateMedia=5 ActiveIPAddrMode=0 IPv4=516991168 IPv6= LoginUser=1002 ProfilePkid= Encoding=2 CtiControllable=2 AppCapability=20 UserCapability=20Protocol=2 MultiMediaCapabilityBitMask=3 DeviceMultiMediaInfo= DeviceVideoCapability=0 TelepresenceInfo=0 ScreenCount=0 TotalLines=1 LineCount=1 MoreLines=F DN = 1002 Part = prt_MSK_TEST PkGpDN = PkGpPart = num RD = 0 StationPid(1,100,74,106) CTIHandlerId(0) LoginStatus=0 06449030.001 |16:21:12.425 |AppError |wait_CtiDeviceRegisterNotifyWithLineInfo ENTER Device=SEPDEADBEAF0002 06449030.002 |16:21:12.425 |AppError | senderPid=(1,106) StationPid=(1,106) 06449031.000 |16:21:12.425 |SdlSig |SIPNotifyReq |wait |SIPHandler(1,100,80,1) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 addrList: 06449031.001 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPIAddContextToTable: Added context(0xcfa7ce0) with key=[34905] to table 06449031.002 |16:21:12.425 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=34917/ccsip_platform_init_scb: scb mgmt added 0xcfa7ce0 (context_id=34905) 06449031.003 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/ccsip_new_scb: Created new scb: 0xcfa7ce0 with id: 34917 06449031.004 |16:21:12.425 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=5060, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0 06449031.005 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 204 (UNDEFINED) 06449031.006 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_CC_NOTIFY] received in State [SUBSCRIBE_STATE_IDLE] 06449031.007 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/sipSPIPutSCBInSubIDTable: Inserting SCB with key 34917 into SubIDTable 06449031.008 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_tcb: Created TCB: 0xcfac7e0 06449031.009 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBParent: TCB 0xcfac7e0's parent is 0xcfa7ce0 06449031.010 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateTCBFromTargetInfo: Dialog Transaction Address 192.168.208.30,Port 5060, Port Present TRUE, Transpo 06449031.011 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPICreateSubscribeKey: Generated Subscribe Key : d2e81980-6b01ad48-80e9-14d0a8c0@192.168.208.20898549443 06449031.012 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/sipSPIPutSCBInClientTable: Inserting SCB with key d2e81980-6b01ad48-80e9-14d0a8c0@192.168.208.20898549443 into ClientTable 06449031.013 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBMethod: TCB 0xcfac7e0's method is 111 06449031.014 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateTCBClientMarker: Client TCB's Marker Updated to: z9hG4bK80ea3222a9c4 06449031.015 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_edb: Created new edb(0xcfc2e18) 06449031.016 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPIUpdateEDBEvent: EDB updated with Event Name: message-summary Event ID: 06449031.017 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/sipSPICreateNewEDB: Q'd EDB(0xcfc2e18) on SCB(0xcfa7ce0) 06449031.018 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPIQueTCBonEDB: Q'd TCB(0xcfac7e0) on EDB(0xcfc2e18) 06449031.019 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/sipSPITCBSendPackage: Sending Package on tcb: 0xcfac7e0 and parent: 0xcfa7ce0, parent context: (nil) 06449031.020 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0xcfa7ce0/sipSPITransportSendMessage: msg=0xcfa8dd8, addr=192.168.208.30, port=5060, sentBy_port=0, is_req=1, trans 06449031.021 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0/sipInstanceGetConnectionId: Registering gcb=0xcfa7ce0 with connection=0xd1db9a0 context list 06449031.022 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0xcfa7ce0/sipTransportLogicSendMsg: Set to send the msg=0xcfa8dd8 06449031.023 |16:21:12.425 |AppInfo |//SIP/Stack/Transport/0x0xcfa7ce0/sipTransportPostSendMessage: Posting send for msg=0xcfa8dd8, addr=192.168.208.30, port=5060, connId=5060 for 06449031.024 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/sipSPIKickoffTcbReTxTimer: Kicking off ReTx Timer for Client TCB (0xcfac7e0) with 10 retries and 500 millis 06449031.025 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/ccsip_new_tcb_timer_context: Created timer context(0xcfa7998) 06449031.026 |16:21:12.425 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer_tcb: timerContext=0xcfa7998 type=SIP_TIMER_CLIENT_TCB value=500 retries=10 06449031.027 |16:21:12.425 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer_tcb: timerContext=0xcfa7998 type=SIP_TIMER_CLIENT_TCB value=500 retries=10 06449031.028 |16:21:12.425 |AppInfo |//SIP/Stack/Info/0x0/act_subscribe_idle_continue_notify_even: Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_ACTIVE 06449032.000 |16:21:12.426 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2854^192.168.208.30^* |*TraceFlagOverrode 06449032.001 |16:21:12.426 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9830,NET] SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac776337481 From: ;tag=1c775948601 To: ;tag=1789228510 Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319744902222016161531@192.168.208.30 Server: Cisco-CUCM11.0 CSeq: 12 REGISTER Expires: 120 Contact: ;x-cisco-newreg Supported: X-cisco-sis-8.0.0 Content-Length: 0 06449033.000 |16:21:12.426 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2854^192.168.208.30^* |*TraceFlagOverrode 06449033.001 |16:21:12.426 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9831,NET] NOTIFY sip:1002@192.168.208.30:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.208.20:5060;branch=z9hG4bK80ea3222a9c4 From: ;tag=898549443 To: Call-ID: d2e81980-6b01ad48-80e9-14d0a8c0@192.168.208.20 CSeq: 101 NOTIFY Max-Forwards: 70 Date: Tue, 02 Feb 2016 13:21:12 GMT User-Agent: Cisco-CUCM11.0 Event: message-summary Subscription-State: active Contact: Content-Type: application/simple-message-summary Content-Length: 22 Messages-Waiting: no 06449034.000 |16:21:12.426 |SdlSig |DeviceStart |initialized |DeviceManager(1,100,208,1) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] Name=SEPDEADBEAF0002 Cepn=54ee2709-0acf-4758-62be-1a0f7e73e292 Type=374 ccmType=4 ProtocolName=SIP PresGrp=ad243d17-98b4-4118-8feb-5ff2e1b781ac Propagate=T IsActive=T Pid= DualModeFlag=01,100,74,106 RoamIP=, geolocInfo={geolocPkid=, filterPkid=, geolocVal=, devType=4} 06449034.001 |16:21:12.426 |AppInfo |DeviceManager:star_DeviceStart Name=SEPDEADBEAF0002 Key=54ee2709-0acf-4758-62be-1a0f7e73e292 RegisterDevice=9 DualModeFlag=0 Propapate=1 protocol=SIP 06449034.002 |16:21:12.426 |AppInfo |mMobileDevice = 0 06449034.003 |16:21:12.426 |AppInfo |DeviceManager:star_DeviceStart The propogation is set to true 06449034.004 |16:21:12.426 |AppInfo |SMDMSharedData::processPendingSub - cepn = 54ee2709-0acf-4758-62be-1a0f7e73e292, devicePid=(1,74,106), localdevice=1 06449034.005 |16:21:12.426 |AppInfo |processResetReqCache::processResetReqCache - currTimeSec - cachedTimeSec=[38] 06449034.006 |16:21:12.426 |AppInfo |DeviceManager:star_DeviceStart Name=SEPDEADBEAF0002 Key=54ee2709-0acf-4758-62be-1a0f7e73e292 OUT 06449035.000 |16:21:12.426 |SdlSig |DMPropagateRegister |initialized |DMPropagation(1,100,215,1) |DeviceManager(1,100,208,1) |1,100,10,1.2854^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] Name=SEPDEADBEAF0002 Cepn=54ee2709-0acf-4758-62be-1a0f7e73e292 Protocol=SIP Pid=1,100,74,106 06449036.000 |16:21:12.426 |SdlSig |DeviceLineRegister |initialize |LineControl(1,100,176,82) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* |[R:L-H:0,N:0,L:0,V:0,Z:0,D:0] PID=(1, 100, 74, 106) Type=374 BusyTrigger=2 Privacy=T Limit=2 UserMOH=0 NetMOH=0 PresGrp=ad243d17-98b4-4118-8feb-5ff2e1b781ac DNDStatus=F DNDOption=0 users.size()=0 users= model=MP-114 FXS/v.6.60A.314.004 DevicePoolVersionStamp= DevicePoolHash= Mac= HLoggedStatus= T 06449037.000 |16:21:12.426 |SdlSig-Q |LmFeatureInd |restart0 |LineControl(1,100,176,82) |SIPStationD(1,100,74,106) |1,100,10,1.2854^192.168.208.30^* | 06449038.000 |16:21:12.426 |SdlSig |DeviceStart |initialized |DeviceManager(1,100,208,1) |LineControl(1,100,176,82) |1,100,10,1.2854^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] Name=1002:8d14c5a4-8a54-20be-c902-fd7c302135d2 Cepn=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 Type=374 ccmType=4 ProtocolName=LINE PresGrp=ad243d17-98b4-4118-8feb-5ff2e1b781ac Propagate=T IsActive=T Pid= DualModeFlag=01,100,176,82 RoamIP=, geolocInfo=null 06449038.001 |16:21:12.426 |AppInfo |DeviceManager:star_DeviceStart Name=1002:8d14c5a4-8a54-20be-c902-fd7c302135d2 Key=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 RegisterDevice=10 DualModeFlag=0 Propapate=1 protocol=LINE 06449038.002 |16:21:12.426 |AppInfo |mMobileDevice = 0 06449038.003 |16:21:12.426 |AppInfo |DeviceManager:star_DeviceStart The propogation is set to true 06449038.004 |16:21:12.426 |AppInfo |SMDMSharedData::findRemoteDeviceList - Key=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 not in RemoteDeviceInfo hashmap 06449038.005 |16:21:12.426 |AppInfo |SMDMSharedData::processPendingSub - cepn = e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9, devicePid=(1,176,82), localdevice=1 06449038.006 |16:21:12.426 |AppInfo |DeviceManager:star_DeviceStart Name=1002:8d14c5a4-8a54-20be-c902-fd7c302135d2 Key=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 OUT 06449039.000 |16:21:12.426 |SdlSig |DMPropagateRegister |initialized |DMPropagation(1,100,215,1) |DeviceManager(1,100,208,1) |1,100,10,1.2854^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] Name=1002:8d14c5a4-8a54-20be-c902-fd7c302135d2 Cepn=e36908ea-7e57-78d3-5d4c-0d5bf0e61ad9 Protocol=LINE Pid=1,100,176,82 06449040.000 |16:21:12.433 |SdlSig |SdlDataInd |wait |SIPUdp(1,100,71,1) |SdlUDPConnection(1,100,10,1) |1,100,10,1.2855^192.168.208.30^* |*TraceFlagOverrode 06449040.001 |16:21:12.433 |AppInfo |//SIP/SIPUdp/wait_SdlDataInd: Incoming SIP UDP message size 712 from 192.168.208.30:[5060]: [9832,NET] REGISTER sip:192.168.208.20 SIP/2.0 Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac776369020 Max-Forwards: 70 From: ;tag=1c775970002 To: Call-ID: 319790537222016161531@192.168.208.30 CSeq: 9 REGISTER Contact: ;expires=180 Authorization: Digest username="1001",realm="ccmsipline",nonce="PV+WTHE8JXEsyUdhlG411pBOUZ4zDE2R",uri="sip:192.168.208.20",algorithm=MD5,response="f95d5260750f1c0a22200921080ef5ec" Supported: path Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Expires: 180 User-Agent: MP-114 FXS/v.6.60A.314.004 Content-Length: 0 06449041.000 |16:21:12.433 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,80,1) |SIPUdp(1,100,71,1) |1,100,10,1.2855^192.168.208.30^* |*TraceFlagOverrode 06449041.001 |16:21:12.433 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 1 (SIPSPI_EV_NEW_MESSAGE) 06449041.002 |16:21:12.433 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWNewConnMsg: context=(nil) 06449041.003 |16:21:12.433 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessNewConnMsg: gConnTab=0xd1d9d20, addr=192.168.208.30, port=5060, connid=5060, transport=UDP 06449041.004 |16:21:12.433 |AppInfo |//SIP/Stack/Info/0x0/sipHolderGetConnInstance: Return existing connection for port 5060 connId 5060 06449041.005 |16:21:12.433 |AppInfo |//SIP/Stack/Info/0x0xcfa90e8/sipSPIFindRegisterCcb: *****CCB found in UAS Request table. ccb=0xcfa90e8 06449041.006 |16:21:12.433 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449041.007 |16:21:12.433 |AppInfo |//SIP/Stack/Transport/0x0/sipSPIUpdateResponseInfo: Subsq Transaction Address 192.168.208.30,Port 5060, Transport 1, SentBy Port 5060 06449041.008 |16:21:12.433 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449041.009 |16:21:12.433 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPITransportSendMessage: msg=0xcfc15d8, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449041.010 |16:21:12.433 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipTransportPostSendMessage: Posting send for msg=0xcfc15d8, addr=192.168.208.30, port=5060, connId=0 for 06449041.011 |16:21:12.433 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: timerContext=0xcfaa0c8 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449041.012 |16:21:12.433 |AppInfo |//SIP/SIPHandler/ccbId=34916/scbId=0/findDevicePID: Routed to SIPStationInit 06449041.013 |16:21:12.433 |AppInfo |//SIP/SIPHandler/ccbId=34916/scbId=0/ccsip_api_register_ind: ++++++ Incrememted gSipNewRegPendingNum to 1 06449041.014 |16:21:12.433 |AppInfo |//SIP/Stack/Event/0x0xcfa90e8/sact_idle_new_message_register: ccsip_api_register_ind return value : SIP_SUCCESS 06449041.015 |16:21:12.433 |AppInfo |//SIP/Stack/States/0x0xcfa90e8/sipSPIChangeState: 0xcfa90e8 : State change from (STATE_IDLE, SUBSTATE_NONE) to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) 06449042.000 |16:21:12.433 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2855^192.168.208.30^* |*TraceFlagOverrode 06449042.001 |16:21:12.433 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9833,NET] SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac776369020 From: ;tag=1c775970002 To: ;tag=1573211610 Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319790537222016161531@192.168.208.30 CSeq: 9 REGISTER Content-Length: 0 06449043.000 |16:21:12.433 |SdlSig |SIPRegisterInd |wait |SIPStationInit(1,100,73,1) |SIPHandler(1,100,80,1) |1,100,10,1.2855^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34916 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449043.001 |16:21:12.433 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449043.002 |16:21:12.433 |AppInfo |SIPStationInit: connId=0, , 1001@192.168.208.30:5060, state=REGISTERED, 3rd-party (RFC-3261) phone found by contact 06449043.003 |16:21:12.433 |AppInfo |SIPStationInit: connID=0, , 192.168.208.30:5060, Routing to D (1,100,74,106), regCount=4 06449044.000 |16:21:12.433 |SdlSig |SIPRegisterInd |wait |SIPStationD(1,100,74,106) |SIPStationInit(1,100,73,1) |1,100,10,1.2855^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34916 --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 MAC = DeviceName = X509SubjectName = SubjectAltname = 06449044.001 |16:21:12.433 |AppInfo |SIPStationD(106) - userStart chars . 2 0 06449044.002 |16:21:12.433 |AppInfo |getExpiresFromContact: expires parameter from Contact: 180 06449044.003 |16:21:12.433 |AppInfo |SIPSecurity::verifyAuthResponse: cred.username=1002, cred.password=5678, auth_hdr.realm=ccmsipline, aut_hdr.nonce=PV+WTHE8JXEsyUdhlG411pBOUZ4zDE2R 06449045.000 |16:21:12.433 |SdlSig |SIPRegisterResp |wait |SIPHandler(1,100,80,1) |SIPStationD(1,100,74,106) |1,100,10,1.2855^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] ccbID= 34916 --TransType=2 --TransSecurity=0 PeerAddr= 192.168.208.30:5060 respCode= 401 action= 2 device= 06449045.001 |16:21:12.433 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPRegisterResp: ++++++ Decrememted gSipNewRegPendingNum to 0 06449045.002 |16:21:12.433 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 3 (SIP_APPLICATION_MSG), for event 43 (SIPSPI_EV_REGISTER_RESP) 06449045.003 |16:21:12.433 |AppInfo |//SIP/Stack/Info/0x0xcfa90e8/sipSPIPushSubsqTransMsgContainerIntoHol: Response Container Holder is above threshold...tri 06449045.004 |16:21:12.434 |AppInfo |//SIP/Stack/Info/0x0xcfa90e8/sipSPISendResponse: Associated container=0xf6145c60 to REGISTER Response 401 06449045.005 |16:21:12.434 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPISendResponse: Sending REGISTER Response to the transport layer 06449045.006 |16:21:12.434 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipSPITransportSendMessage: msg=0xcce8bc8, addr=192.168.208.30, port=5060, sentBy_port=5060, is_req=0, trans 06449045.007 |16:21:12.434 |AppInfo |//SIP/Stack/Transport/0x0xcfa90e8/sipTransportPostSendMessage: Posting send for msg=0xcce8bc8, addr=192.168.208.30, port=5060, connId=0 for 06449045.008 |16:21:12.434 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: timerContext=0xcfaa0c8 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449045.009 |16:21:12.434 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: timerContext=0xcfaa0c8 type=SIP_TIMER_REMOVE_TRANSACTION value=32000 retries=0 06449045.010 |16:21:12.434 |AppInfo |//SIP/Stack/States/0x0xcfa90e8/sipSPIChangeState: 0xcfa90e8 : State change from (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE) to (STATE_IDLE, SUBSTATE_NONE) 06449046.000 |16:21:12.434 |SdlSig |SIPSPISignal |wait |SIPUdp(1,100,71,1) |SIPHandler(1,100,80,1) |1,100,10,1.2855^192.168.208.30^* |*TraceFlagOverrode 06449046.001 |16:21:12.434 |AppInfo |//SIP/SIPUdp/wait_SdlSPISignal: Outgoing SIP UDP message to 192.168.208.30:[5060]: [9834,NET] SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.208.30:5060;branch=z9hG4bKac776369020 From: ;tag=1c775970002 To: ;tag=1573211610 Date: Tue, 02 Feb 2016 13:21:12 GMT Call-ID: 319790537222016161531@192.168.208.30 CSeq: 9 REGISTER WWW-Authenticate: Digest realm="ccmsipline", nonce="PV+WTHE8JXEsyUdhlG411pBOUZ4zDE2R", algorithm=MD5 Content-Length: 0 06449047.000 |16:21:12.459 |SdlSig |SdlDataInd |wait |SIPUdp(1,100,71,1) |SdlUDPConnection(1,100,10,1) |1,100,10,1.2856^192.168.208.30^* |*TraceFlagOverrode 06449047.001 |16:21:12.459 |AppInfo |//SIP/SIPUdp/wait_SdlDataInd: Incoming SIP UDP message size 447 from 192.168.208.30:[5060]: [9835,NET] SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.208.20:5060;branch=z9hG4bK80ea3222a9c4 From: ;tag=898549443 To: ;tag=1c776423856 Call-ID: d2e81980-6b01ad48-80e9-14d0a8c0@192.168.208.20 CSeq: 101 NOTIFY Contact: Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Server: MP-114 FXS/v.6.60A.314.004 Content-Length: 0 06449048.000 |16:21:12.459 |SdlSig |SIPSPISignal |wait |SIPHandler(1,100,80,1) |SIPUdp(1,100,71,1) |1,100,10,1.2856^192.168.208.30^* |*TraceFlagOverrode 06449048.001 |16:21:12.459 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 2 (SIP_NETWORK_MSG), for event 1 (SIPSPI_EV_NEW_MESSAGE) 06449048.002 |16:21:12.459 |AppInfo |//SIP/Stack/Transport/0x0/sipTransportProcessNWNewConnMsg: context=(nil) 06449048.003 |16:21:12.459 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerProcessNewConnMsg: gConnTab=0xd1d9d20, addr=192.168.208.30, port=5060, connid=5060, transport=UDP 06449048.004 |16:21:12.459 |AppInfo |//SIP/Stack/Info/0x0/sipHolderGetConnInstance: Return existing connection for port 5060 connId 5060 06449048.005 |16:21:12.459 |AppInfo |//SIP/Stack/Info/0x0/sipSPICreateSubscribeKey: Generated Subscribe Key : d2e81980-6b01ad48-80e9-14d0a8c0@192.168.208.20898549443 06449048.006 |16:21:12.459 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_event: Event [SIPSPI_EV_NEW_MSG] received in State [SUBSCRIBE_STATE_ACTIVE] 06449048.007 |16:21:12.459 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer_tcb: timerContext=0xcfa7998 type=SIP_TIMER_CLIENT_TCB value=500 retries=10 06449048.008 |16:21:12.459 |AppInfo |//SIP/Stack/Info/0x0xcfac7e0/sipSPIFreeOneTCB: Invoking sipSPIFreeOneTCB - Ref count: 3 06449048.009 |16:21:12.460 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=34917/findDevicePID: Routed to SIPD by ccbId/scbId 06449048.010 |16:21:12.460 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/sipSPIReportRequestSendStatus: ccsip_api_notify_result returned: SIP_SUCCESS 06449049.000 |16:21:12.460 |SdlSig |SIPNotifyResult |wait |SIPStationD(1,100,74,106) |SIPHandler(1,100,80,1) |1,100,10,1.2856^192.168.208.30^* |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] --TransType=2 --TransSecurity=0 PeerAddr = 192.168.208.30:5060 06449049.001 |16:21:12.460 |AppInfo |SIPStationD(106) - Terminating transaction for scbId 34917, pkgId 7 06449050.000 |16:21:12.460 |SdlSig |SIPSubTerminated |wait |SIPHandler(1,100,80,1) |SIPStationD(1,100,74,106) |1,100,10,1.2856^192.168.208.30^* |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0] --TransType=2 --TransSecurity=0 PeerAddr=192.168.208.30:5060 scbId=34917 event.id= event.name=message-summary eventType=0 06449050.001 |16:21:12.460 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPSubTerminated: inDialogSubReferCounter=0 scbid=34917 06449050.002 |16:21:12.460 |AppInfo |//SIP/Stack/Info/0x0/ccsip_process_sipspi_queue_event: ccsip_spi_get_msg_type returned: 7 (SIP_APPLICATION_SUBSCRIBE_NOTIFY_MSG), for event 210 (UNDEFINED) 06449050.003 |16:21:12.460 |AppInfo |//SIP/Stack/Info/0x0/ccsip_spi_process_app_subscribe_event: Event [SIPSPI_EV_SUBSCRIPTION_TERMINATED] received in State [SUBSCRIBE_STATE_ACTIVE] 06449050.004 |16:21:12.460 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/sipSPITerminateEvent: Freeing SCB(0xcfa7ce0), 0 active events present 06449050.005 |16:21:12.460 |AppInfo |//SIP/Stack/Transport/0x0/sipConnectionManagerUnregisterCtxtInCon: gConnTab=0xd1d9d20, addr=192.168.208.30, port=5060, unregistering context= 06449050.006 |16:21:12.460 |AppInfo |//SIP/SIPHandler/ccbId=0/scbId=34917/ccsip_platform_delete_scb: scb mgmt deleted 0xcfa7ce0 (context_id=34905) 06449050.007 |16:21:12.460 |AppInfo |//SIP/Stack/Info/0x0xcfa7ce0/sipSPIFreeOneSCB: Freeing SCB(0xcfa7ce0) 06449050.008 |16:21:12.460 |AppInfo |//SIP/Stack/Info/0x0xcfac7e0/_sipSPIFreeOneTCB: Invoking SIP TCB destructor 06449050.009 |16:21:12.460 |AppInfo |//SIP/Stack/Info/0x0xcfac7e0/_sipSPIFreeOneTCB: Invoking nullify package