Kurt Harnish Posted July 26, 2011 Report Share Posted July 26, 2011 Using 2011-4.2.1.4025 (Win64) This is the log when we try to use Call Pickup SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.83:2048;branch=z9hG4bK-s7vmz7xww33l;rport=43175;received=someipaddress From: "889" <sip:889@someipaddress>;tag=1129uy0e3n To: <sip:*87@someipaddress;user=phone>;tag=ea5a27f58a Call-ID: 3c2672a2cbc1-zaevthd57h5d CSeq: 2 INVITE Content-Length: 0 [4] 2011/07/26 10:12:48: Ignoring directed pickup request for call leg because the difference of -882384765 is too short [7] 2011/07/26 10:12:48: Undirected call pickup failed [7] 2011/07/26 10:12:48: set_codecs: for 3c2672a2cbc1-zaevthd57h5d codecs "", codec_preference count 7 [7] 2011/07/26 10:12:48: Set packet length to 20 [0] 2011/07/26 10:12:48: SIP Tx udp:24.229.49.16:43175: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.1.83:2048;branch=z9hG4bK-s7vmz7xww33l;rport=43175;received=24.229.49.16 From: "889" <sip:889@someipaddress>;tag=1129uy0e3n To: <sip:*87@someipaddress;user=phone>;tag=ea5a27f58a Call-ID: 3c2672a2cbc1-zaevthd57h5d CSeq: 2 INVITE Contact: <sip:889@10.1.255.1:5060> Supported: 100rel, replaces, norefersub Allow-Events: refer Allow: INVITE, ACK, CANCEL, BYE, REFER, PRACK, INFO, UPDATE Accept: application/sdp User-Agent: pbxnsip-PBX/2011-4.2.1.4025 Content-Length: 0 Quote Link to comment Share on other sites More sharing options...
pbx support Posted July 26, 2011 Report Share Posted July 26, 2011 Not able to reproduce it here. Are you picking this up "too soon"? At this moment I am not sure if this has to do with "Mailbox Direct Dial Prefix" and extension number both starting with 8. BTW, I am assuming here that you have not changed the mailbox prefix. Let's know otherwise. Quote Link to comment Share on other sites More sharing options...
karim.makki Posted August 25, 2011 Report Share Posted August 25, 2011 Hello everyone, I have been encountering this error too on a multi-domain snomblue. [4] 2011/08/25 16:37:26: Ignoring directed pickup request for call leg because the difference of -1266815360 is too short This is the installation in general: - Snom telephones are registered on the SnomBlue (which is not installed on the same network) - There are three domains at the moment - Telephones are configured manually, and not provisionned - The phones have 3 digit extensions that begin with a 2 or a 3 - The call pickup failed with the same error when using *02 and *82 as a Call Pickup star code - The SnomBlue is on the 3981 version, linux distribution - Note that another client on the same SnomBlue is not having problems with Call Pickup (and using the default *87 starcode) Kurt, were you able to solve the issue? could you please provide feedback if so? Thank you Quote Link to comment Share on other sites More sharing options...
pbx support Posted August 25, 2011 Report Share Posted August 25, 2011 In this domain, if you set the code back to *87, does it work? Also, if this happens all the time or when there is more than 1 call on that extension, or any other clues? Quote Link to comment Share on other sites More sharing options...
karim.makki Posted August 29, 2011 Report Share Posted August 29, 2011 In this domain, if you set the code back to *87, does it work? Also, if this happens all the time or when there is more than 1 call on that extension, or any other clues? Hello, Thanks for your reply. In fact i haven't had time to test with the client when using the default star code, i will test it and get back with the results as soon as possible. During the tests, we tried to intercept when there was only one call on the extension Regards Quote Link to comment Share on other sites More sharing options...
karim.makki Posted August 29, 2011 Report Share Posted August 29, 2011 Hi again, The *87 was tested today, but i have the same problem as before (when using another star code) Here is the Error: Ignoring directed pickup request for call leg because the difference of -920966761 is too short PS: The negative value changes form one call to another Best Regards, Karim Makki Quote Link to comment Share on other sites More sharing options...
karim.makki Posted September 2, 2011 Report Share Posted September 2, 2011 Hello "support", Would you have feedback on this issue plz? Thanks Quote Link to comment Share on other sites More sharing options...
pbx support Posted September 6, 2011 Report Share Posted September 6, 2011 We did several tests here and could not reproduce the issue. Can you please send us the Admin->Status page & the log with level 8 set? Quote Link to comment Share on other sites More sharing options...
karim.makki Posted September 8, 2011 Report Share Posted September 8, 2011 Hello, After deactivation of one of the domain trunks on the Snom Blue (now there are three), we are able to intercept when using *87 A directed Call pickup (*87401) returns the same error as mentioned above. We tried to replace the *87 with *02, and we have the same symptoms (blind call pickup successful, directed call pickup fails) Here is the call log when using *87 then *87401: [5] 2011/09/08 16:32:11: Identify trunk (IP address and DID match) 10 [7] 2011/09/08 16:32:11: Set packet length to 20 [6] 2011/09/08 16:32:11: Sending RTP for 30e0ad9e56cdfcc04f20c1661bac586f@87.98.200.138 to 87.98.200.138:14516, codec not set yet [8] 2011/09/08 16:32:11: Call from a trunk 10 [8] 2011/09/08 16:32:11: Trunk Corea-MS@ipbx.corea.mystream.org has country code not set, area code not set [8] 2011/09/08 16:32:11: To is <sip:401@109.70.19.42;user=phone>, user 0, domain 1 [5] 2011/09/08 16:32:11: Domain trunk Corea-MS@ipbx.corea.mystream.org sends call to 401 in domain ipbx.corea.mystream.org [8] 2011/09/08 16:32:11: Set the To domain based on To user 401@ipbx.corea.mystream.org [8] 2011/09/08 16:32:11: Call state for call object 2327: idle [8] 2011/09/08 16:32:11: Call state for call object 2327: alerting [8] 2011/09/08 16:32:11: Play audio_moh/noise.wav [7] 2011/09/08 16:32:11: set_codecs: for 30e0ad9e56cdfcc04f20c1661bac586f@87.98.200.138 codecs "0 8", codec_preference count 3 [7] 2011/09/08 16:32:11: set_codecs: for e1d13890@pbx codecs "", codec_preference count 7 [7] 2011/09/08 16:32:11: Set packet length to 20 [8] 2011/09/08 16:32:12: Play audio_fr/ringback.wav [6] 2011/09/08 16:32:12: Codec pcmu/8000 is chosen for call id 30e0ad9e56cdfcc04f20c1661bac586f@87.98.200.138 [7] 2011/09/08 16:32:12: Call e1d13890@pbx: Clear last request [8] 2011/09/08 16:32:15: Could not find a trunk (5 trunks) [8] 2011/09/08 16:32:15: Using outbound proxy sip:80.13.50.65:52845;transport=udp because UDP packet source did not match the via header [8] 2011/09/08 16:32:15: Tagging request with existing tag [7] 2011/09/08 16:32:15: Set packet length to 20 [6] 2011/09/08 16:32:15: Sending RTP for NDYwYzc4ODY4NDE0NGUwYThlNjNhMzA3NWE2YzM3MWQ. to 80.13.50.65:40030, codec not set yet [8] 2011/09/08 16:32:15: Call from an user 402 [8] 2011/09/08 16:32:15: To is <sip:*87401@ipbx.corea.mystream.org:5060>, user 0, domain 6 [8] 2011/09/08 16:32:15: From user 402 [8] 2011/09/08 16:32:15: Set the To domain based on From user 402@ipbx.corea.mystream.org [8] 2011/09/08 16:32:15: Call state for call object 2328: idle [4] 2011/09/08 16:32:15: Ignoring directed pickup request for call leg because the difference of -57526214 is too short [7] 2011/09/08 16:32:15: set_codecs: for NDYwYzc4ODY4NDE0NGUwYThlNjNhMzA3NWE2YzM3MWQ. codecs "", codec_preference count 7 [7] 2011/09/08 16:32:15: Set packet length to 20 [8] 2011/09/08 16:32:18: Could not find a trunk (5 trunks) [8] 2011/09/08 16:32:18: Using outbound proxy sip:80.13.50.65:52845;transport=udp because UDP packet source did not match the via header [8] 2011/09/08 16:32:19: Tagging request with existing tag [7] 2011/09/08 16:32:19: Set packet length to 20 [6] 2011/09/08 16:32:19: Sending RTP for MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM. to 80.13.50.65:40034, codec not set yet [8] 2011/09/08 16:32:19: Call from an user 402 [8] 2011/09/08 16:32:19: To is <sip:*87@ipbx.corea.mystream.org:5060>, user 0, domain 6 [8] 2011/09/08 16:32:19: From user 402 [8] 2011/09/08 16:32:19: Set the To domain based on From user 402@ipbx.corea.mystream.org [8] 2011/09/08 16:32:19: Call state for call object 2329: idle [8] 2011/09/08 16:32:19: Call state for call object 2327: connected [7] 2011/09/08 16:32:19: set_codecs: for MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM. codecs "", codec_preference count 7 [7] 2011/09/08 16:32:19: Set packet length to 20 [6] 2011/09/08 16:32:19: Codec pcmu/8000 is chosen for call id MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM. [6] 2011/09/08 16:32:19: Last message repeated 2 times [7] 2011/09/08 16:32:19: 30e0ad9e56cdfcc04f20c1661bac586f@87.98.200.138: RTP pass-through mode [7] 2011/09/08 16:32:19: MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM.: RTP pass-through mode [7] 2011/09/08 16:32:19: Call e1d13890@pbx: Clear last request [7] 2011/09/08 16:32:19: Call e1d13890@pbx: Clear last INVITE [5] 2011/09/08 16:32:19: INVITE Response 487 Request Terminated: Terminate e1d13890@pbx [6] 2011/09/08 16:32:19: Sending RTP for MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM. to 80.13.50.65:2880, codec pcmu/8000 [8] 2011/09/08 16:32:25: Hangup: Call 347 not found [7] 2011/09/08 16:32:25: MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM.: Media-aware pass-through mode [8] 2011/09/08 16:32:25: Hangup: Call 347 not found [7] 2011/09/08 16:32:25: Call MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM.: Clear last request [5] 2011/09/08 16:32:25: BYE Response: Terminate MjA4NDdlMzI4NjE4MjAwNjc1OTFjZTU5MWI2ZGMzZDM. Thanks in advance Quote Link to comment Share on other sites More sharing options...
pbx support Posted September 8, 2011 Report Share Posted September 8, 2011 Only red flag is that first call says domain 1 and the second says domain 6. [8] 2011/09/08 16:32:11: To is <sip:401@109.70.19.42;user=phone>, user 0, domain 1 [8] 2011/09/08 16:32:15: To is <sip:*87401@ipbx.corea.mystream.org:5060>, user 0, domain 6 Not sure that is causing this issue at this moment. Also, by looking at the log, it seems like an external call coming in and ringing an extension 401. Is that true or was it any different call flow? Any info (like special settings on the trunk/extension etc) would be helpful to reproduce it here. We can add bit more logging and provide you with a new version for you to try. Let us know. Quote Link to comment Share on other sites More sharing options...
karim.makki Posted September 9, 2011 Report Share Posted September 9, 2011 Hello again, The first call that you see on the domain 1 is the initial incoming call, and not the attempt to do a directed pickup call. I don't know why the SnomBlue is receiving the call on Domain1; maybe all incoming calls are routed to domain1 by default, then the SnomBlue searches for the corresponding domain? Concerning the incoming call ringing directly on extension 401, in fact we controle the server on which we have the registered trunk. so i routed a call a bit in a hurry directly to 401, since i haven't issigned a 10 digit number to that extension. The customer facing this problem will have all it's incoming calls routed via our voip plateform next Thursday (15 September), so i appreciate if we could pass this issue to an urgent status Thanks again EDIT: Hello again, i did some call pickup tests with the "localhost" domain (successfully), and when i returned to test with the domain where the problem occurs, oddly i could intercept with no problem! i will send in another reply the call pickup log. Quote Link to comment Share on other sites More sharing options...
karim.makki Posted September 9, 2011 Report Share Posted September 9, 2011 Here is the logfile level 8 on both tries (*02 and *02401) [8] 2011/09/09 14:54:44: Call from a trunk 10 [8] 2011/09/09 14:54:44: Trunk Corea-MS@ipbx.corea.mystream.org has country code not set, area code not set [8] 2011/09/09 14:54:44: To is <sip:401@109.70.19.42;user=phone>, user 0, domain 1 [5] 2011/09/09 14:54:44: Domain trunk Corea-MS@ipbx.corea.mystream.org sends call to 401 in domain ipbx.corea.mystream.org [8] 2011/09/09 14:54:44: Set the To domain based on To user 401@ipbx.corea.mystream.org [8] 2011/09/09 14:54:44: Call state for call object 2490: idle [8] 2011/09/09 14:54:44: Call state for call object 2490: alerting [8] 2011/09/09 14:54:44: Play audio_moh/noise.wav [7] 2011/09/09 14:54:44: set_codecs: for 76a9b0611d5f4b29629cea0c787e3a6b@87.98.200.138 codecs "0 8", codec_preference count 3 [7] 2011/09/09 14:54:44: set_codecs: for b76d0169@pbx codecs "", codec_preference count 7 [7] 2011/09/09 14:54:44: Set packet length to 20 [8] 2011/09/09 14:54:44: Play audio_fr/ringback.wav [6] 2011/09/09 14:54:44: Codec pcmu/8000 is chosen for call id 76a9b0611d5f4b29629cea0c787e3a6b@87.98.200.138 [8] 2011/09/09 14:54:47: Could not find a trunk (5 trunks) [8] 2011/09/09 14:54:47: Using outbound proxy sip:80.13.50.65:2048;transport=udp because UDP packet source did not match the via header [8] 2011/09/09 14:54:48: Tagging request with existing tag [7] 2011/09/09 14:54:48: Set packet length to 20 [6] 2011/09/09 14:54:48: Sending RTP for 3c27b09d044c-5bg96ovz7hqh to 10.8.1.78:58186, codec not set yet [8] 2011/09/09 14:54:48: Call from an user 402 [8] 2011/09/09 14:54:48: To is <sip:*02@ipbx.corea.mystream.org;user=phone>, user 0, domain 6 [8] 2011/09/09 14:54:48: From user 402 [8] 2011/09/09 14:54:48: Set the To domain based on From user 402@ipbx.corea.mystream.org [8] 2011/09/09 14:54:48: Call state for call object 2491: idle [8] 2011/09/09 14:54:48: Call state for call object 2490: connected [7] 2011/09/09 14:54:48: set_codecs: for 3c27b09d044c-5bg96ovz7hqh codecs "", codec_preference count 7 [7] 2011/09/09 14:54:48: Set packet length to 20 [6] 2011/09/09 14:54:48: Codec pcmu/8000 is chosen for call id 3c27b09d044c-5bg96ovz7hqh [6] 2011/09/09 14:54:48: Last message repeated 2 times [7] 2011/09/09 14:54:48: 76a9b0611d5f4b29629cea0c787e3a6b@87.98.200.138: RTP pass-through mode [7] 2011/09/09 14:54:48: 3c27b09d044c-5bg96ovz7hqh: RTP pass-through mode [7] 2011/09/09 14:54:48: Call 3c27b09d044c-5bg96ovz7hqh: Clear last request [6] 2011/09/09 14:54:48: Sending RTP for 3c27b09d044c-5bg96ovz7hqh to 80.13.50.65:58186, codec pcmu/8000 [7] 2011/09/09 14:54:48: Call b76d0169@pbx: Clear last request [7] 2011/09/09 14:54:48: Call b76d0169@pbx: Clear last INVITE [5] 2011/09/09 14:54:48: INVITE Response 487 Request Terminated: Terminate b76d0169@pbx [8] 2011/09/09 14:54:50: Hangup: Call 231 not found [7] 2011/09/09 14:54:50: 3c27b09d044c-5bg96ovz7hqh: Media-aware pass-through mode [8] 2011/09/09 14:54:50: Hangup: Call 231 not found [7] 2011/09/09 14:54:50: Call 3c27b09d044c-5bg96ovz7hqh: Clear last request [5] 2011/09/09 14:54:50: BYE Response: Terminate 3c27b09d044c-5bg96ovz7hqh [5] 2011/09/09 14:54:58: Identify trunk (IP address and DID match) 10 [7] 2011/09/09 14:54:58: Set packet length to 20 [6] 2011/09/09 14:54:58: Sending RTP for 231b66cd00cc37a43ebcd871419765aa@87.98.200.138 to 87.98.200.138:19970, codec not set yet [8] 2011/09/09 14:54:58: Call from a trunk 10 [8] 2011/09/09 14:54:58: Trunk Corea-MS@ipbx.corea.mystream.org has country code not set, area code not set [8] 2011/09/09 14:54:58: To is <sip:401@109.70.19.42;user=phone>, user 0, domain 1 [5] 2011/09/09 14:54:58: Domain trunk Corea-MS@ipbx.corea.mystream.org sends call to 401 in domain ipbx.corea.mystream.org [8] 2011/09/09 14:54:58: Set the To domain based on To user 401@ipbx.corea.mystream.org [8] 2011/09/09 14:54:58: Call state for call object 2492: idle [8] 2011/09/09 14:54:58: Call state for call object 2492: alerting [8] 2011/09/09 14:54:58: Play audio_moh/noise.wav [7] 2011/09/09 14:54:58: set_codecs: for 231b66cd00cc37a43ebcd871419765aa@87.98.200.138 codecs "0 8", codec_preference count 3 [7] 2011/09/09 14:54:58: set_codecs: for 1bcf3e62@pbx codecs "", codec_preference count 7 [7] 2011/09/09 14:54:58: Set packet length to 20 [8] 2011/09/09 14:54:58: Play audio_fr/ringback.wav [6] 2011/09/09 14:54:58: Codec pcmu/8000 is chosen for call id 231b66cd00cc37a43ebcd871419765aa@87.98.200.138 [8] 2011/09/09 14:55:02: Could not find a trunk (5 trunks) [8] 2011/09/09 14:55:02: Using outbound proxy sip:80.13.50.65:2048;transport=udp because UDP packet source did not match the via header [8] 2011/09/09 14:55:02: Tagging request with existing tag [7] 2011/09/09 14:55:02: Set packet length to 20 [6] 2011/09/09 14:55:02: Sending RTP for 3c27b0a9f33d-8ten0xrimcig to 10.8.1.78:62090, codec not set yet [8] 2011/09/09 14:55:02: Call from an user 402 [8] 2011/09/09 14:55:02: To is <sip:*02401@ipbx.corea.mystream.org;user=phone>, user 0, domain 6 [8] 2011/09/09 14:55:02: From user 402 [8] 2011/09/09 14:55:02: Set the To domain based on From user 402@ipbx.corea.mystream.org [8] 2011/09/09 14:55:02: Call state for call object 2493: idle [8] 2011/09/09 14:55:02: Call state for call object 2492: connected [7] 2011/09/09 14:55:02: set_codecs: for 3c27b0a9f33d-8ten0xrimcig codecs "", codec_preference count 7 [7] 2011/09/09 14:55:02: Set packet length to 20 [6] 2011/09/09 14:55:02: Codec pcmu/8000 is chosen for call id 3c27b0a9f33d-8ten0xrimcig [6] 2011/09/09 14:55:02: Last message repeated 2 times [7] 2011/09/09 14:55:02: 231b66cd00cc37a43ebcd871419765aa@87.98.200.138: RTP pass-through mode [7] 2011/09/09 14:55:02: 3c27b0a9f33d-8ten0xrimcig: RTP pass-through mode [7] 2011/09/09 14:55:02: Call 3c27b0a9f33d-8ten0xrimcig: Clear last request [6] 2011/09/09 14:55:02: Sending RTP for 3c27b0a9f33d-8ten0xrimcig to 80.13.50.65:62090, codec pcmu/8000 [7] 2011/09/09 14:55:02: Call 1bcf3e62@pbx: Clear last request [7] 2011/09/09 14:55:02: Call 1bcf3e62@pbx: Clear last INVITE [5] 2011/09/09 14:55:02: INVITE Response 487 Request Terminated: Terminate 1bcf3e62@pbx [8] 2011/09/09 14:55:06: Hangup: Call 241 not found [7] 2011/09/09 14:55:06: 231b66cd00cc37a43ebcd871419765aa@87.98.200.138: Media-aware pass-through mode [8] 2011/09/09 14:55:06: Hangup: Call 241 not found [7] 2011/09/09 14:55:06: Call 231b66cd00cc37a43ebcd871419765aa@87.98.200.138: Clear last request [5] 2011/09/09 14:55:06: BYE Response: Terminate 231b66cd00cc37a43ebcd871419765aa@87.98.200.138 Kind Regards Quote Link to comment Share on other sites More sharing options...
pbx support Posted September 9, 2011 Report Share Posted September 9, 2011 The first call that you see on the domain 1 is the initial incoming call, and not the attempt to do a directed pickup call. I don't know why the SnomBlue is receiving the call on Domain1; maybe all incoming calls are routed to domain1 by default, then the SnomBlue searches for the corresponding domain? I understand that. The domain selection is based on the trunk where the call was received. BTW, the 1, 6 are all some internal indices. We perform the call pickup without any issues here all the time, on a typical setup. Since you are seeing issues there, I wanted to know if you have any special setup there. Anyways, let us know if you bump into the issue again(maybe with the SIP trace too along with the traces that you have sent). Quote Link to comment Share on other sites More sharing options...
karim.makki Posted September 9, 2011 Report Share Posted September 9, 2011 Ok, will let you know if the issue reappears (i hope not ). Thanks for your replies. Regards Quote Link to comment Share on other sites More sharing options...
karim.makki Posted October 4, 2011 Report Share Posted October 4, 2011 Hello, The problem reappeared alone today on all domains, the same way it disappeared Did you do more tests on this issue? Thanks Quote Link to comment Share on other sites More sharing options...
pbx support Posted October 4, 2011 Report Share Posted October 4, 2011 There was an issue that we fixed in that area couple of weeks ago & tested on one of our customers installation too. Please try http://pbxnsip.com/snomone/beta/win64/pbxctrl-2011-4.3.0.5010.exe Quote Link to comment Share on other sites More sharing options...
Recommended Posts
Join the conversation
You can post now and register later. If you have an account, sign in now to post with your account.
Note: Your post will require moderator approval before it will be visible.