error registering IP phones

Discussion in 'General' started by iouser, Jun 29, 2010.

  1. iouser

    Joined:
    Jun 29, 2010
    Messages:
    1
    Likes Received:
    0
    Hi we have a machine with Elastix setup in our Melbourne office.

    elastix v1.6-12
    asterisk v1.4.26.1

    The Melbourne office has a number of phones working fine.

    We also have a New Zealand office, which we are connected to via a VPN. The phone in NZ were working fine, but after changing ISP's in New Zealand, our phones are no longer registering.

    Our softphones in NZ are still working OK, but not the physical phones. We are using Polycom IP 601 phones.

    As shown in the log below, when we hard reset the NZ phones, we are getting the 'Registration failed User: 118, Error Code:480 Temporarily not available' message.

    We are therefore unable to make or receive phonecalls from these phones.

    We CAN interact with the NZ phones via web interface, from the Melbourne office, so they are on the network, just not registering with Elastix.

    I have googled the error message and couldn't really find anything useful to point me in the right direction as to why we are getting it.

    There are a few other errors before the registration one, but after investigating the logs on phones that DO register OK, they also have those same errors, so I am not concerned about them for now.

    Any help much appreciated!

    Code:
    1006291148|app1 |*|03|Initial log entry. Current logging level 4
    1006291148|app1 |4|03|Default StatusBar is disabled
    1006291148|app1 |4|03|mb.main.home parameter is empty
    1006291148|mb   |*|03|Initial log entry. Current logging level 4
    1006291148|so   |*|03|[SoNcasC]: App-Ctx (RichardJ) [0-118]
    1006291148|slog |*|03|Initial log entry. Current logging level 4
    1006291148|copy |4|03|Upload of 'polycom/logs/0004f210e188-app.log' FAILED on attempt 1
    1006291148|log  |4|03|UtilLogC::uploadLog: upload error. protocol 0 result = 9
    1006291148|copy |4|03|Download of 'SoundPointIPWelcome.wav' FAILED on attempt 1
    1006291148|res  |4|03|[ResFinderC]: Download - Failed to download file SoundPointIPWelcome.wav, errno 0x388002.
    1006291148|so   |4|03|[SoToneC]::Failed to find tone SoundPointIPWelcome.wav - using default
    1006291149|sip  |4|03|Registration failed User: 118, Error Code:480 Temporarily not available
    
    Update: Still looking into this issue, but I found out how to output more detailed logs to do with the sip.

    As you can see in the log output below, it is continuously trying to send a 'subscribe' message to the asterisk machine. I checked a logfile of a phone that works and there is an immediate response message from asterisk, there is never a response recieved on the non-working phones.

    So the million dollar question, why isn't asterisk sending a response back?

    I am now investigating 2 things, is asterisk recieving the message, but just not being able to send a reply? Or is it never recieving the messages in the first place?

    Code:
    1006301510|sip  |3|03|Retry send 281
    1006301510|sip  |3|03|SendMessageFail
    1006301510|sip  |3|03|CStateSubscribeClient::OnEvResponse 480
    1006301510|sip  |1|03|Client State finished SUBSCRIBE
    1006301510|sip  |1|03|CreateFailOverProxyList : Reg to Domain '192.168.5.84' nPort 5060
    1006301510|sip  |1|03|CreateFailOverProxyList : For REGISTER Request nPort 5060
    1006301510|sip  |1|03|doDnsFullLookup(UDP): given IP address 192.168.5.84 port 5060
    1006301510|sip  |1|03|doDnsListLookup(UDP): doDnsFullLookup for '192.168.5.84' port 5060 returned 1 results
    1006301510|sip  |1|03|doDnsListLookup(UDP): result 0 '192.168.5.84' port 5060
    1006301510|sip  |1|03|CreateFailOverProxyList : Not NAPTR for '192.168.5.84' port 5060 IP 0 is '192.168.5.84' on UDP port 5060
    1006301510|sip  |2|03|CreateFailOverProxyList : Exit with 1 IP Addresses
    1006301510|sip  |2|03|CreateFailOverProxyList : IP 1 is '192.168.5.84' on UDP port 5060
    1006301510|sip  |0|03|>>> Data Send to 192.168.5.84:5060
    1006301510|sip  |0|03|    REGISTER sip:192.168.5.84:5060 SIP/2.0
    1006301510|sip  |0|03|    Via: SIP/2.0/UDP 192.168.8.152;branch=z9hG4bKf3353b69753BB8C4
    1006301510|sip  |0|03|    From: "RichardJ" <sip:118@192.168.5.84>;tag=8A26A98B-988A0230
    1006301510|sip  |0|03|    To: <sip:118@192.168.5.84>
    1006301510|sip  |0|03|    CSeq: 1 REGISTER
    1006301510|sip  |0|03|    Call-ID: ae61d81f-793cfcd5-3973997a@192.168.8.152
    1006301510|sip  |0|03|    Contact: <sip:118@192.168.8.152>;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE
    1006301510|sip  |0|03|    , REFER"
    1006301510|sip  |0|03|    User-Agent: PolycomSoundPointIP-SPIP_601-UA/2.2.2.0084
    1006301510|sip  |0|03|    Max-Forwards: 70
    1006301510|sip  |0|03|    Expires: 3600
    1006301510|sip  |0|03|    Content-Length: 0
    1006301510|sip  |0|03|    
    1006301510|sip  |0|03|>>> Data Send to 192.168.5.84:5060
    1006301510|sip  |0|03|    REGISTER sip:192.168.5.84:5060 SIP/2.0
    1006301510|sip  |0|03|    Via: SIP/2.0/UDP 192.168.8.152;branch=z9hG4bKf3353b69753BB8C4
    1006301510|sip  |0|03|    From: "RichardJ" <sip:118@192.168.5.84>;tag=8A26A98B-988A0230
    1006301510|sip  |0|03|    To: <sip:118@192.168.5.84>
    1006301510|sip  |0|03|    CSeq: 1 REGISTER
    1006301510|sip  |0|03|    Call-ID: ae61d81f-793cfcd5-3973997a@192.168.8.152
    1006301510|sip  |0|03|    Contact: <sip:118@192.168.8.152>;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE
    1006301510|sip  |0|03|    , REFER"
    1006301510|sip  |0|03|    User-Agent: PolycomSoundPointIP-SPIP_601-UA/2.2.2.0084
    1006301510|sip  |0|03|    Max-Forwards: 70
    1006301510|sip  |0|03|    Expires: 3600
    1006301510|sip  |0|03|    Content-Length: 0
    
     

Share This Page