Hi,
i use the dialog module. i create the dialog using dlg_manage() right after the invite is recieved. in a case where the final destination rejects the call (486 busy), i get the following error: " WARNING: dialog [dlg_handlers.c:1054]: unable to find dialog for ACK with route param....." i guess the dialog module deletes the dialog on the 486 reply and gives me this erro when an ACK for the 486 is recieved from the caller...
no a bug issue, but, is there a way to overcome it?
thanks, Uri
Hey,
Am 23.01.2012 um 11:13 schrieb Uri Shacked:
i use the dialog module. i create the dialog using dlg_manage() right after the invite is recieved. in a case where the final destination rejects the call (486 busy), i get the following error: " WARNING: dialog [dlg_handlers.c:1054]: unable to find dialog for ACK with route param....." i guess the dialog module deletes the dialog on the 486 reply and gives me this erro when an ACK for the 486 is recieved from the caller...
No, the module shouldn't behave like that. IIRC, a terminating dialog is tied to its underlying transaction and should stay alive until the transaction times out.
Does each ACK message include a proper dialog ID (i.e., To-tag, From-tag, and Call-ID corresponding to the 486 message)? Is the ACK routed statefully at all?
Cheers,
--Timo
Hi,
here is the trace of the end of the call. 486 is recieved from the destination, kamailio sends ACK to it. then kamailio sends the 486 to the origination and recieves an ACK for it. (the scenario is behind the companies gateway....)
*No. Time Source Destination Protocol Length Info 19 5.199372 172.16.10.2 172.16.60.161 SIP 533 Status: 486 Busy Here* *Frame 19: 533 bytes on wire (4264 bits), 533 bytes captured (4264 bits) Arrival Time: Jan 23, 2012 11:49:29.701743000 Jerusalem Standard Time Epoch Time: 1327312169.701743000 seconds [Time delta from previous captured frame: 1.208615000 seconds] [Time delta from previous displayed frame: 1.208615000 seconds] [Time since reference or first frame: 5.199372000 seconds] Frame Number: 19 Frame Length: 533 bytes (4264 bits) Capture Length: 533 bytes (4264 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ip:udp:sip] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Cisco_2e:84:80 (00:11:5c:2e:84:80), Dst: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) Destination: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) Address: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Source: Cisco_2e:84:80 (00:11:5c:2e:84:80) Address: Cisco_2e:84:80 (00:11:5c:2e:84:80) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Type: IP (0x0800) Internet Protocol Version 4, Src: 172.16.10.2 (172.16.10.2), Dst: 172.16.60.161 (172.16.60.161) Version: 4 Header length: 20 bytes Differentiated Services Field: 0x00 (DSCP 0x00: Default; ECN: 0x00: Not-ECT (Not ECN-Capable Transport)) 0000 00.. = Differentiated Services Codepoint: Default (0x00) .... ..00 = Explicit Congestion Notification: Not-ECT (Not ECN-Capable Transport) (0x00) Total Length: 519 Identification: 0x3289 (12937) Flags: 0x02 (Don't Fragment) 0... .... = Reserved bit: Not set .1.. .... = Don't fragment: Set ..0. .... = More fragments: Not set Fragment offset: 0 Time to live: 253 Protocol: UDP (17) Header checksum: 0xaa98 [correct] [Good: True] [Bad: False] Source: 172.16.10.2 (172.16.10.2) Destination: 172.16.60.161 (172.16.60.161) User Datagram Protocol, Src Port: sip (5060), Dst Port: sip (5060) Source port: sip (5060) Destination port: sip (5060) Length: 499 Checksum: 0x2e26 [validation disabled] [Good Checksum: False] [Bad Checksum: False] Session Initiation Protocol Status-Line: SIP/2.0 486 Busy Here Status-Code: 486 [Resent Packet: False] Message Header Call-ID: 6998600418003572708-1327312311-1053 From: sip:506775651;ss7-cpc=10;cpc=ordinary@172.16.10.2 ;user=phone;tag=6998600418003572708 SIP from address: sip:506775651;ss7-cpc=10;cpc= ordinary@172.16.10.2;user=phone SIP from address User Part: 506775651;ss7-cpc=10;cpc=ordinary SIP from address Host Part: 172.16.10.2 SIP tag: 6998600418003572708 To: sip:39955011@172.16.60.161;user=phone;tag=9304443427217266661 SIP to address: sip:39955011@172.16.60.161;user=phone SIP to address User Part: 39955011 SIP to address Host Part: 172.16.60.161 SIP tag: 9304443427217266661 CSeq: 1 INVITE Sequence Number: 1 Method: INVITE Via: SIP/2.0/UDP 172.16.60.161;received=172.16.60.161;branch=z9hG4bKba0a.ac0fb354.2,SIP/2.0/UDP 172.16.10.2:5060;branch=z9hG4bK-612006000000d7e4-ac100a02-1 Transport: UDP Sent-by Address: 172.16.60.161 Received: 172.16.60.161 Branch: z9hG4bKba0a.ac0fb354.2 Transport: UDP Sent-by Address: 172.16.10.2 Sent-by port: 5060 Branch: z9hG4bK-612006000000d7e4-ac100a02-1 Supported: timer,100rel Reason: Q.850;cause=17;text="UserBusy" Reason Protocols: Q.850 Cause: 17(0x11)[User busy] Content-Length: 0* *No. Time Source Destination Protocol Length Info 20 5.199640 172.16.60.161 172.16.10.2 SIP 416 Request: ACK sip:0504014041@172.16.10.2;user=phone* *Frame 20: 416 bytes on wire (3328 bits), 416 bytes captured (3328 bits) Arrival Time: Jan 23, 2012 11:49:29.702011000 Jerusalem Standard Time Epoch Time: 1327312169.702011000 seconds [Time delta from previous captured frame: 0.000268000 seconds] [Time delta from previous displayed frame: 0.000268000 seconds] [Time since reference or first frame: 5.199640000 seconds] Frame Number: 20 Frame Length: 416 bytes (3328 bits) Capture Length: 416 bytes (3328 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ip:udp:sip] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5), Dst: Cisco_2e:84:80 (00:11:5c:2e:84:80) Destination: Cisco_2e:84:80 (00:11:5c:2e:84:80) Address: Cisco_2e:84:80 (00:11:5c:2e:84:80) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Source: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) Address: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Type: IP (0x0800) Internet Protocol Version 4, Src: 172.16.60.161 (172.16.60.161), Dst: 172.16.10.2 (172.16.10.2) Version: 4 Header length: 20 bytes Differentiated Services Field: 0x10 (DSCP 0x04: Unknown DSCP; ECN: 0x00: Not-ECT (Not ECN-Capable Transport)) 0001 00.. = Differentiated Services Codepoint: Unknown (0x04) .... ..00 = Explicit Congestion Notification: Not-ECT (Not ECN-Capable Transport) (0x00) Total Length: 402 Identification: 0x0f78 (3960) Flags: 0x00 0... .... = Reserved bit: Not set .0.. .... = Don't fragment: Not set ..0. .... = More fragments: Not set Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xcb0f [correct] [Good: True] [Bad: False] Source: 172.16.60.161 (172.16.60.161) Destination: 172.16.10.2 (172.16.10.2) User Datagram Protocol, Src Port: sip (5060), Dst Port: sip (5060) Source port: sip (5060) Destination port: sip (5060) Length: 382 Checksum: 0x5975 [validation disabled] [Good Checksum: False] [Bad Checksum: False] Session Initiation Protocol Request-Line: ACK sip:0504014041@172.16.10.2;user=phone SIP/2.0 Method: ACK Request-URI: sip:0504014041@172.16.10.2;user=phone Request-URI User Part: 0504014041 Request-URI Host Part: 172.16.10.2 [Resent Packet: False] [Request Frame: 18] [Response Time (ms): 1238] Message Header Call-ID: 6998600418003572708-1327312311-1053 From: sip:506775651;ss7-cpc=10;cpc=ordinary@172.16.10.2 ;user=phone;tag=6998600418003572708 SIP from address: sip:506775651;ss7-cpc=10;cpc= ordinary@172.16.10.2;user=phone SIP from address User Part: 506775651;ss7-cpc=10;cpc=ordinary SIP from address Host Part: 172.16.10.2 SIP tag: 6998600418003572708 To: sip:39955011@172.16.60.161;user=phone;tag=9304443427217266661 SIP to address: sip:39955011@172.16.60.161;user=phone SIP to address User Part: 39955011 SIP to address Host Part: 172.16.60.161 SIP tag: 9304443427217266661 CSeq: 1 ACK Sequence Number: 1 Method: ACK Via: SIP/2.0/UDP 172.16.60.161;branch=z9hG4bKba0a.ac0fb354.2 Transport: UDP Sent-by Address: 172.16.60.161 Branch: z9hG4bKba0a.ac0fb354.2 Max-Forwards: 69 Content-Length: 0* *No. Time Source Destination Protocol Length Info 21 5.199772 172.16.60.161 172.16.10.2 SIP 454 Status: 486 Busy Here* *Frame 21: 454 bytes on wire (3632 bits), 454 bytes captured (3632 bits) Arrival Time: Jan 23, 2012 11:49:29.702143000 Jerusalem Standard Time Epoch Time: 1327312169.702143000 seconds [Time delta from previous captured frame: 0.000132000 seconds] [Time delta from previous displayed frame: 0.000132000 seconds] [Time since reference or first frame: 5.199772000 seconds] Frame Number: 21 Frame Length: 454 bytes (3632 bits) Capture Length: 454 bytes (3632 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ip:udp:sip] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5), Dst: Cisco_2e:84:80 (00:11:5c:2e:84:80) Destination: Cisco_2e:84:80 (00:11:5c:2e:84:80) Address: Cisco_2e:84:80 (00:11:5c:2e:84:80) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Source: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) Address: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Type: IP (0x0800) Internet Protocol Version 4, Src: 172.16.60.161 (172.16.60.161), Dst: 172.16.10.2 (172.16.10.2) Version: 4 Header length: 20 bytes Differentiated Services Field: 0x10 (DSCP 0x04: Unknown DSCP; ECN: 0x00: Not-ECT (Not ECN-Capable Transport)) 0001 00.. = Differentiated Services Codepoint: Unknown (0x04) .... ..00 = Explicit Congestion Notification: Not-ECT (Not ECN-Capable Transport) (0x00) Total Length: 440 Identification: 0x0f79 (3961) Flags: 0x00 0... .... = Reserved bit: Not set .0.. .... = Don't fragment: Not set ..0. .... = More fragments: Not set Fragment offset: 0 Time to live: 64 Protocol: UDP (17) Header checksum: 0xcae8 [correct] [Good: True] [Bad: False] Source: 172.16.60.161 (172.16.60.161) Destination: 172.16.10.2 (172.16.10.2) User Datagram Protocol, Src Port: sip (5060), Dst Port: sip (5060) Source port: sip (5060) Destination port: sip (5060) Length: 420 Checksum: 0x91b9 [validation disabled] [Good Checksum: False] [Bad Checksum: False] Session Initiation Protocol Status-Line: SIP/2.0 486 Busy Here Status-Code: 486 [Resent Packet: False] Message Header Call-ID: 6998600418003572708-1327312311-1053 From: sip:506775651;ss7-cpc=10;cpc=ordinary@172.16.10.2 ;user=phone;tag=6998600418003572708 SIP from address: sip:506775651;ss7-cpc=10;cpc= ordinary@172.16.10.2;user=phone SIP from address User Part: 506775651;ss7-cpc=10;cpc=ordinary SIP from address Host Part: 172.16.10.2 SIP tag: 6998600418003572708 To: sip:39955011@172.16.60.161;user=phone;tag=9304443427217266661 SIP to address: sip:39955011@172.16.60.161;user=phone SIP to address User Part: 39955011 SIP to address Host Part: 172.16.60.161 SIP tag: 9304443427217266661 CSeq: 1 INVITE Sequence Number: 1 Method: INVITE Via: SIP/2.0/UDP 172.16.10.2:5060 ;branch=z9hG4bK-612006000000d7e4-ac100a02-1 Transport: UDP Sent-by Address: 172.16.10.2 Sent-by port: 5060 Branch: z9hG4bK-612006000000d7e4-ac100a02-1 Supported: timer,100rel Reason: Q.850;cause=17;text="UserBusy" Reason Protocols: Q.850 Cause: 17(0x11)[User busy] Content-Length: 0* *No. Time Source Destination Protocol Length Info 22 5.210511 172.16.10.2 172.16.60.161 SIP 520 Request: ACK sip:172.16.10.2:5060* *Frame 22: 520 bytes on wire (4160 bits), 520 bytes captured (4160 bits) Arrival Time: Jan 23, 2012 11:49:29.712882000 Jerusalem Standard Time Epoch Time: 1327312169.712882000 seconds [Time delta from previous captured frame: 0.010739000 seconds] [Time delta from previous displayed frame: 0.010739000 seconds] [Time since reference or first frame: 5.210511000 seconds] Frame Number: 22 Frame Length: 520 bytes (4160 bits) Capture Length: 520 bytes (4160 bits) [Frame is marked: False] [Frame is ignored: False] [Protocols in frame: eth:ip:udp:sip] [Coloring Rule Name: UDP] [Coloring Rule String: udp] Ethernet II, Src: Cisco_2e:84:80 (00:11:5c:2e:84:80), Dst: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) Destination: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) Address: Silicom_05:b5:f5 (00:e0:ed:05:b5:f5) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Source: Cisco_2e:84:80 (00:11:5c:2e:84:80) Address: Cisco_2e:84:80 (00:11:5c:2e:84:80) .... ...0 .... .... .... .... = IG bit: Individual address (unicast) .... ..0. .... .... .... .... = LG bit: Globally unique address (factory default) Type: IP (0x0800) Internet Protocol Version 4, Src: 172.16.10.2 (172.16.10.2), Dst: 172.16.60.161 (172.16.60.161) Version: 4 Header length: 20 bytes Differentiated Services Field: 0x00 (DSCP 0x00: Default; ECN: 0x00: Not-ECT (Not ECN-Capable Transport)) 0000 00.. = Differentiated Services Codepoint: Default (0x00) .... ..00 = Explicit Congestion Notification: Not-ECT (Not ECN-Capable Transport) (0x00) Total Length: 506 Identification: 0x328a (12938) Flags: 0x02 (Don't Fragment) 0... .... = Reserved bit: Not set .1.. .... = Don't fragment: Set ..0. .... = More fragments: Not set Fragment offset: 0 Time to live: 253 Protocol: UDP (17) Header checksum: 0xaaa4 [correct] [Good: True] [Bad: False] Source: 172.16.10.2 (172.16.10.2) Destination: 172.16.60.161 (172.16.60.161) User Datagram Protocol, Src Port: sip (5060), Dst Port: sip (5060) Source port: sip (5060) Destination port: sip (5060) Length: 486 Checksum: 0xec7a [validation disabled] [Good Checksum: False] [Bad Checksum: False] Session Initiation Protocol Request-Line: ACK sip:172.16.10.2:5060 SIP/2.0 Method: ACK Request-URI: sip:172.16.10.2:5060 Request-URI Host Part: 172.16.10.2 Request-URI Host Port: 5060 [Resent Packet: False] [Request Frame: 19] [Response Time (ms): 1249] Message Header Call-ID: 6998600418003572708-1327312311-1053 From: sip:506775651;ss7-cpc=10;cpc=ordinary@172.16.10.2 ;user=phone;tag=6998600418003572708 SIP from address: sip:506775651;ss7-cpc=10;cpc= ordinary@172.16.10.2;user=phone SIP from address User Part: 506775651;ss7-cpc=10;cpc=ordinary SIP from address Host Part: 172.16.10.2 SIP tag: 6998600418003572708 To: sip:39955011@172.16.60.161;user=phone;tag=9304443427217266661 SIP to address: sip:39955011@172.16.60.161;user=phone SIP to address User Part: 39955011 SIP to address Host Part: 172.16.60.161 SIP tag: 9304443427217266661 CSeq: 1 ACK Sequence Number: 1 Method: ACK Via: SIP/2.0/UDP 172.16.10.2:5060 ;branch=z9hG4bK-612006000000d7e4-ac100a02-1 Transport: UDP Sent-by Address: 172.16.10.2 Sent-by port: 5060 Branch: z9hG4bK-612006000000d7e4-ac100a02-1 Route: sip:172.16.60.161;lr=on;ftag=6998600418003572708;did=47.c7f00ed6 Max-Forwards: 70 User-Agent: VCS 5.8.2.51-04 Content-Length: 0 *
On Mon, Jan 23, 2012 at 9:24 PM, Timo Reimann sr@foo-lounge.de wrote:
Hey,
Am 23.01.2012 um 11:13 schrieb Uri Shacked:
i use the dialog module. i create the dialog using dlg_manage() right after the invite is
recieved.
in a case where the final destination rejects the call (486 busy), i get
the following error:
" WARNING: dialog [dlg_handlers.c:1054]: unable to find dialog for ACK
with route param....."
i guess the dialog module deletes the dialog on the 486 reply and gives
me this erro when an ACK for the 486 is recieved from the caller...
No, the module shouldn't behave like that. IIRC, a terminating dialog is tied to its underlying transaction and should stay alive until the transaction times out.
Does each ACK message include a proper dialog ID (i.e., To-tag, From-tag, and Call-ID corresponding to the 486 message)? Is the ACK routed statefully at all?
Cheers,
--Timo
-
Timo Reimann -
Uri Shacked