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