Apple Event: May 7th at 7 am PT

Looks like no one’s replied in a while. To start the conversation again, simply ask a new question.

Level 2

159 points

SIP Connection Errors

I've been having connection problems (i.e. can't ever connect via video/audio/screen-sharing) for several months now and I'm fed up - need a solution. 🙂

I've seem this same problem posted many times, but there's never a clear answer (despite Ralph's courageous attempts). Everything was working fine before (in 10.5.6), and out of the blue, everything just seemed to stop working. I upgraded to 10.5.7 thinking that perhaps it would refresh the iChat install, but no joy.

I've tried all of the common tricks (clamping the bandwidth, opening up the firewall, removing sys hacks, etc.) with no help. I get the same results while trying to connect to a remote user via AIM, with a LAN user via Mac.com, or with a LAN user directly via Bonjour.

I'm on a G5 Quad with an iSight, tons of RAM, a fast 6M/756k DSL pipe routing through a TimeCapsule with NAT port mapping on, and nothing else particularly weird installed in my system. Skype works fine, so this seems to be exclusively an iChat problem.

I'd really appreciate any suggestions. I'm a fairly savvy Mac user (21 years), but this one has me stumped and I REALLY don't want to do a clean install.

BTW, my laptop also seems to have this problem now, but I believe my kid's eMac can still audio/video chat just fine, which tends to rule out a problem with my router/modem (I'll verify this tomorrow and post a follow-up).

Here's my latest console dump from an audio chat test to appleu3test03 (everything but the binaries):

Date/Time: 2009-05-20 01:04:59.843 -0700
OS Version: 10.5.7 (Build 9J61)
Report Version: 4

iChat Connection Log:
2009-05-20 01:04:20 -0700: AVChat started with ID 2751450677.
2009-05-20 01:04:20 -0700: me@mac.com5: State change from AVChatNoState to AVChatStateWaiting.
2009-05-20 01:04:20 -0700: 0x1a638170: State change from AVChatNoState to AVChatStateInvited.
2009-05-20 01:04:28 -0700: 0x1a638170: State change from AVChatStateInvited to AVChatStateConnecting.
2009-05-20 01:04:28 -0700: me@mac.com5: State change from AVChatStateWaiting to AVChatStateConnecting.
2009-05-20 01:04:57 -0700: 0x1a638170: State change from AVChatStateConnecting to AVChatStateEnded.
2009-05-20 01:04:57 -0700: 0x1a638170: Error -8 (Did not receive a response from 0x1a638170.)
2009-05-20 01:04:57 -0700: me@mac.com5: State change from AVChatStateConnecting to AVChatStateEnded.
2009-05-20 01:04:57 -0700: me@mac.com5: Error -8 (Did not receive a response from 0x1a638170.)

Video Conference Error Report:
15.567366 @SIP/SIP.c:2719 type=4 (900A0015/0)
[SIPConnectIPPort failed]
[]
98.027383 @SIP/SIP.c:2719 type=4 (900A0015/0)
[SIPConnectIPPort failed]
[]
202.609920 @SIP/SIP.c:2719 type=4 (900A0015/0)
[SIPConnectIPPort failed]
[]

Video Conference Support Report:
13.566973 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK0ea6da9b55436abc
Max-Forwards: 70
To: "u0" <sip:user@rip>
From: "0" <sip:user@lip:16402>;tag=1682922493
Call-ID: 6d39f516-4514-11de-8812-de666e0e4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 737

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:1390750600
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:3951961097
]
[]
14.067631 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK0ea6da9b55436abc
Max-Forwards: 70
To: "u0" <sip:user@rip>
From: "0" <sip:user@lip:16402>;tag=1682922493
Call-ID: 6d39f516-4514-11de-8812-de666e0e4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 737

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:1390750600
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:3951961097
]
[]
15.068019 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK0ea6da9b55436abc
Max-Forwards: 70
To: "u0" <sip:user@rip>
From: "0" <sip:user@lip:16402>;tag=1682922493
Call-ID: 6d39f516-4514-11de-8812-de666e0e4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 737

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:1390750600
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:3951961097
]
[]
96.026923 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK1267afd405812773
Max-Forwards: 70
To: "u0" <sip:user@rip>
From: "0" <sip:user@lip:16402>;tag=1117135655
Call-ID: 9e5fb248-4514-11de-8812-99133b1e4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 728

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:3449004105
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:0
]
[]
96.527597 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK1267afd405812773
Max-Forwards: 70
To: "u0" <sip:user@rip>
From: "0" <sip:user@lip:16402>;tag=1117135655
Call-ID: 9e5fb248-4514-11de-8812-99133b1e4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 728

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:3449004105
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:0
]
[]
97.528072 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK1267afd405812773
Max-Forwards: 70
To: "u0" <sip:user@rip>
From: "0" <sip:user@lip:16402>;tag=1117135655
Call-ID: 9e5fb248-4514-11de-8812-99133b1e4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 728

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:3449004105
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:0
]
[]
188.165815 @Video Conference/VCInitiateConference.m:1584 type=2 (00000000/0)
[Connection Data for call id: 3 returns 1
]
[]
189.593153 @Video Conference/VCInitiateConference.m:1599 type=2 (00000000/0)
[Prepare Connection With Remote Data - remote VCConnectionData: 1, local VCConnectionData: 1
]
[]
189.596757 @Video Conference/VCInitiateConference.m:1703 type=2 (00000000/0)
[Initiate Conference To User: u0 with Remote VCConnectionData: 1 with Local Connection Data: 1 conferenceSettings: 1]
[]
200.609256 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK67868b5d39c0d21f
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=1714278690
Call-ID: de057072-4514-11de-8812-f1fdfa814012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 401

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:2407868870
]
[]
201.109909 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK67868b5d39c0d21f
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=1714278690
Call-ID: de057072-4514-11de-8812-f1fdfa814012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 401

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:2407868870
]
[]
202.110514 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK67868b5d39c0d21f
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=1714278690
Call-ID: de057072-4514-11de-8812-f1fdfa814012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 401

v=0
o=greyling 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:YES
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:2407868870
]
[]

Video Conference User Report:
0.000000 @:0 type=5 (00000000/16402)
[Local SIP port]
[]
0.000030 @:0 type=5 (00000000/16402)
[Local SIP port]
[]
6.251797 @Video Conference/VideoConferenceMultiController.m:1474 type=5 (00000000/0)
[IP And Port Data With Caller IP And Port Data: Obtained 160 bytes of local IP and port data (4 entries). Remote data was 0 bytes (0 entries).
]
[]
8.527729 @Video Conference/VideoConferenceMultiController.m:1507 type=5 (00000000/0)
[Initiate Conference To User Cert Version: u0 with 120 bytes of connection data.
]
[]
88.994600 @Video Conference/VideoConferenceMultiController.m:1474 type=5 (00000000/0)
[IP And Port Data With Caller IP And Port Data: Obtained 160 bytes of local IP and port data (4 entries). Remote data was 0 bytes (0 entries).
]
[]
91.018530 @Video Conference/VideoConferenceMultiController.m:1507 type=5 (00000000/0)
[Initiate Conference To User Cert Version: u0 with 120 bytes of connection data.
]
[]
208.608046 @Video Conference/VideoConferenceMultiController.m:1474 type=5 (00000000/0)
[IP And Port Data With Caller IP And Port Data: Obtained 160 bytes of local IP and port data (4 entries). Remote data was 0 bytes (0 entries).
]
[]

-- SNIP --

Thanks,

-Mark

Posted on May 20, 2009 1:33 AM

38 replies

Mark Lindsey Author

Level 2

159 points

May 27, 2009 2:02 PM in response to Ralph-Johns-UK

No, I'm still getting a slew of the <QCPatch errors when I try to initiate a video chat. 😟

SInce it's been a while, here's the latest iChat error dump, which still shows the From/To IPs mising:

Date/Time: 2009-05-27 11:46:52.670 -0700
OS Version: 10.5.7 (Build 9J61)
Report Version: 4

iChat Connection Log:
2009-05-27 11:46:13 -0700: AVChat started with ID 2901591205.
2009-05-27 11:46:13 -0700: username@mac.com3: State change from AVChatNoState to AVChatStateWaiting.
2009-05-27 11:46:13 -0700: 0x1966bb60: State change from AVChatNoState to AVChatStateInvited.
2009-05-27 11:46:20 -0700: 0x1966bb60: State change from AVChatStateInvited to AVChatStateConnecting.
2009-05-27 11:46:20 -0700: username@mac.com3: State change from AVChatStateWaiting to AVChatStateConnecting.
2009-05-27 11:46:49 -0700: 0x1966bb60: State change from AVChatStateConnecting to AVChatStateEnded.
2009-05-27 11:46:49 -0700: 0x1966bb60: Error -8 (Did not receive a response from 0x1966bb60.)
2009-05-27 11:46:49 -0700: username@mac.com3: State change from AVChatStateConnecting to AVChatStateEnded.
2009-05-27 11:46:49 -0700: username@mac.com3: Error -8 (Did not receive a response from 0x1966bb60.)

Video Conference Error Report:
20.005028 @SIP/SIP.c:2719 type=4 (900A0015/0)
[SIPConnectIPPort failed]
[]
1986.012065 @SIP/SIP.c:2719 type=4 (900A0015/0)
[SIPConnectIPPort failed]
[]

Video Conference Support Report:
6.149146 @Video Conference/VCInitiateConference.m:1584 type=2 (00000000/0)
[Connection Data for call id: 1 returns 1
]
[]
6.988140 @Video Conference/VCInitiateConference.m:1599 type=2 (00000000/0)
[Prepare Connection With Remote Data - remote VCConnectionData: 1, local VCConnectionData: 1
]
[]
6.992455 @Video Conference/VCInitiateConference.m:1703 type=2 (00000000/0)
[Initiate Conference To User: u0 with Remote VCConnectionData: 1 with Local Connection Data: 1 conferenceSettings: 1]
[]
18.004450 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK52229cf23c840852
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=1930852309
Call-ID: 1e11daf4-4aea-11de-a5ac-cf5f9d494012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 400

v=0
o=username 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:NO
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:4271495752
]
[]
18.505020 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK52229cf23c840852
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=1930852309
Call-ID: 1e11daf4-4aea-11de-a5ac-cf5f9d494012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 400

v=0
o=username 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:NO
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:4271495752
]
[]
19.505641 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK52229cf23c840852
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=1930852309
Call-ID: 1e11daf4-4aea-11de-a5ac-cf5f9d494012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 400

v=0
o=username 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:NO
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:4271495752
]
[]
1971.834901 @Video Conference/VCInitiateConference.m:1584 type=2 (00000000/0)
[Connection Data for call id: 3 returns 1
]
[]
1972.993913 @Video Conference/VCInitiateConference.m:1599 type=2 (00000000/0)
[Prepare Connection With Remote Data - remote VCConnectionData: 1, local VCConnectionData: 1
]
[]
1972.995561 @Video Conference/VCInitiateConference.m:1703 type=2 (00000000/0)
[Initiate Conference To User: u0 with Remote VCConnectionData: 1 with Local Connection Data: 1 conferenceSettings: 1]
[]
1984.011428 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK62faf63634418ba9
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=150155898
Call-ID: b1d6fbd0-4aee-11de-a5ac-8d05cc9f4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 736

v=0
o=username 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:NO
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:1316442755
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:1486069843
]
[]
1984.511894 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK62faf63634418ba9
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=150155898
Call-ID: b1d6fbd0-4aee-11de-a5ac-8d05cc9f4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 736

v=0
o=username 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:NO
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:1316442755
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:1486069843
]
[]
1985.512826 @SIP/Transport.c:2362 type=1 (00000000/0)
[INVITE sip:user@rip:16402 SIP/2.0
Via: SIP/2.0/UDP lip:16402;branch=z9hG4bK62faf63634418ba9
Max-Forwards: 70
To: "u0" <sip:user@rip:16402>
From: "0" <sip:user@lip:16402>;tag=150155898
Call-ID: b1d6fbd0-4aee-11de-a5ac-8d05cc9f4012@lip
CSeq: 1 INVITE
Contact: <sip:user@lip:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 736

v=0
o=username 0 0 IN IP4 lip
s=0
c=IN IP4 lip
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:NO
a=bandwidthDetection:YES
m=audio 16402 RTP/AVP 110 121 12 3 0
a=rtcp:16402
a=rtpmap:121 speex/16000
a=rtpmap:122 speex/8000
a=rtpmap:113 X-AAC_LD/44100
a=rtpmap:110 X-AAC_LD/22050
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpID:1316442755
m=video 16402 RTP/AVP 123 126 34
a=rtcp:16402
a=rtpmap:123 H264/90000
a=rtpmap:126 X-H264/90000
a=rtpmap:34 H263/90000
a=fmtp:34 imagesize 1 rules 30:352:288
a=framerate:30
a=RTCP:AUDIO 16402 VIDEO 16402
a=fmtp:126 imagesize 0 rules 30:640:480:640:480:30
a=fmtp:123 imagesize 0 rules 30:640:480:640:480:30
a=rtpID:1486069843
]
[]

Video Conference User Report:
0.000000 @:0 type=5 (00000000/16402)
[Local SIP port]
[]
0.000028 @:0 type=5 (00000000/16402)
[Local SIP port]
[]
26.001822 @Video Conference/VideoConferenceMultiController.m:1474 type=5 (00000000/0)
[IP And Port Data With Caller IP And Port Data: Obtained 160 bytes of local IP and port data (4 entries). Remote data was 0 bytes (0 entries).
]
[]
1992.019131 @Video Conference/VideoConferenceMultiController.m:1474 type=5 (00000000/0)
[IP And Port Data With Caller IP And Port Data: Obtained 160 bytes of local IP and port data (4 entries). Remote data was 0 bytes (0 entries).
]
[]

-------

Here's a fresh terminal dump - it stalls at the end and never finishes, and notice that the ONLY IP listed now is the wrong one (2.1 instead of 4.1). Something is seriously wrong here.

username:~ username$ /Applications/iChat.app/Contents/MacOS/iChat -errorLogLevel 7
135137.758042 ER_AddFilter (0): Adding [username@127.0.0.1]->[0]
135137.758170 setLocalUserName: username@127.0.0.1
135137.764607 Creating a port mapping from 52274 to 16402...
135137.766201 Default video size is (640 x 480)
135137.766392 H264: (640 x 480) @ 30 fps
135137.766490 H264: (320 x 240) @ 30 fps
135137.766535 H264: (160 x 120) @ 30 fps
135137.766619 H263: (352 x 288) @ 30 fps
135137.766651 H263: (176 x 144) @ 30 fps
135137.767331 ######################################
135137.767377 # SoundDec_Create(81536 --> 393219) #
135137.767400 ######################################
135137.784519 Leaving SoundDec_Create
135137.785301 ### NEW STATE: to: VC_INIT, from: VC_INVALID
135137.785621 ### NEW STATE: to: VC_IDLE, from: VC_INIT
135137.785677 ++++++++ confStatusMapRemoveAll
135137.785703 ( ) -- mapUserIDToSecurityInfo cleared --
135137.785816 NATTraversal created
135137.786245 Bandwidth based on QT prefs = 1500
135137.865617 SNATMAP Configuration: snatmap.mac.com:5678
135138.021375 Selected microphone is the null device microphone.
135138.022043 vcCapabilitiesOfCPU returns: 0x00000fff
135138.022146 VCCapAudio
135138.022166 VCCapVideo
135138.022183 VCCapMultiAudio
135138.022199 VCCapMultiVideo
135138.022215 VCCapMultiAudioHost
135138.022261 VCCapMultiVideoHost
135138.022279 VCCapRDClient
135138.022296 VCCapRDServer
135138.022313 VCCapRecordAudio
135138.022329 VCCapRecordVideo
135138.022345 VCCapAuxVideoSend
135138.022384 VCCapAuxVideoRecv
135138.022990 BWD: GotBandwidth: 677799/2548778 (up/down) @[192.168.2.1:255.255.255.0]
135138.023049 BWD bandwidth: 677 kbits up, 2548 kbits down.
135138.023067 videoNetworkCapable: 1 -- 3 max participants.
135138.023089 audioNetworkCapable: 1 -- 9 max participants.
135138.023106 Supports PCMU wideband for audio: 1 / video: 1
135138.023179 vcCapabilitiesOfNetwork returns: 0x00000fff
135138.023204 VCCapAudio
135138.023221 VCCapVideo
135138.023237 VCCapMultiAudio
135138.023253 VCCapMultiVideo
135138.023268 VCCapMultiAudioHost
135138.023285 VCCapMultiVideoHost
135138.023302 VCCapRDClient
135138.023319 VCCapRDServer
135138.023334 VCCapRecordAudio
135138.023350 VCCapRecordVideo
135138.023367 VCCapAuxVideoSend
135138.023384 VCCapAuxVideoRecv
135138.023400 vcCapabilities returns: 0x00000fff
135138.023416 VCCapAudio
135138.023434 VCCapVideo
135138.023450 VCCapMultiAudio
135138.023465 VCCapMultiVideo
135138.023482 VCCapMultiAudioHost
135138.023499 VCCapMultiVideoHost
135138.023515 VCCapRDClient
135138.023530 VCCapRDServer
135138.023547 VCCapRecordAudio
135138.024860 VCCapRecordVideo
135138.024931 VCCapAuxVideoSend
135138.024947 VCCapAuxVideoRecv
135138.027586 Found 1 cameras:
135138.030613 iSight - 2857630788241996
135138.055156 Microphone devices:
135138.055443 iSight Built-in
135138.055668 Line In
135138.056641 Digital In
135138.061848 Plantronics Headset
135138.062367 Selected microphone is the null device microphone.
135138.063840 vcCapabilitiesOfCPU returns: 0x00000fff
135138.063923 VCCapAudio
135138.063941 VCCapVideo
135138.063957 VCCapMultiAudio
135138.063974 VCCapMultiVideo
135138.063990 VCCapMultiAudioHost
135138.064006 VCCapMultiVideoHost
135138.064023 VCCapRDClient
135138.064039 VCCapRDServer
135138.064055 VCCapRecordAudio
135138.064071 VCCapRecordVideo
135138.064088 VCCapAuxVideoSend
135138.064104 VCCapAuxVideoRecv
135138.064589 BWD: GotBandwidth: 677799/2548778 (up/down) @[192.168.2.1:255.255.255.0]
135138.064889 BWD bandwidth: 677 kbits up, 2548 kbits down.
135138.064912 videoNetworkCapable: 1 -- 3 max participants.
135138.064929 audioNetworkCapable: 1 -- 9 max participants.
135138.064948 Supports PCMU wideband for audio: 1 / video: 1
135138.065092 vcCapabilitiesOfNetwork returns: 0x00000fff
135138.065113 VCCapAudio
135138.065129 VCCapVideo
135138.065144 VCCapMultiAudio
135138.065161 VCCapMultiVideo
135138.065178 VCCapMultiAudioHost
135138.065194 VCCapMultiVideoHost
135138.065210 VCCapRDClient
135138.065228 VCCapRDServer
135138.065244 VCCapRecordAudio
135138.065260 VCCapRecordVideo
135138.065277 VCCapAuxVideoSend
135138.065293 VCCapAuxVideoRecv
135138.065309 vcCapabilities returns: 0x00000fff
135138.065326 VCCapAudio
135138.065343 VCCapVideo
135138.065359 VCCapMultiAudio
135138.065375 VCCapMultiVideo
135138.065391 VCCapMultiAudioHost
135138.065410 VCCapMultiVideoHost
135138.065426 VCCapRDClient
135138.065441 VCCapRDServer
135138.065458 VCCapRecordAudio
135138.065475 VCCapRecordVideo
135138.065491 VCCapAuxVideoSend
135138.065506 VCCapAuxVideoRecv
135138.067029 Found 1 cameras:
135138.067130 iSight - 2857630788241996
135138.068029 selectCameraByGUID: 2857630788241996 returned 1
135138.153487 Microphone devices:
135138.153764 iSight Built-in
135138.154014 Line In
135138.155273 Digital In
135138.156301 Plantronics Headset
135138.156568 Selected mic: Plantronics Headset
135138.218322 Speaker devices:
135138.218499 283 Built-in Audio
135138.218543 278 Plantronics Headset
135138.272468 Selected speaker: Plantronics Headset
135138.563300 in callback
135138.563389 -sdRef 0x16f70d10 ?
135138.563408 -flags = 00000000
135138.563677 -IFIndex en2 ?
135138.563698 -errorCode == 00000000
135138.563723 -publicAddr == ••• WAN IP shown here •••
135138.563740 -proto == 16
135138.563757 -privPort == 16402
135138.563775 -publPort == 52274
135138.563792 -ttl == 3600
135138.570509 Selected microphone is Plantronics Headset.
135138.571308 Selected microphone is Plantronics Headset.
135138.571934 Selected microphone is Plantronics Headset.
135139.172941 Comm reporting flags: 00000102
135139.190695 CommNAT result:
External IP: ••• WAN IP shown here •••
External Ports: 34313, 34313, 34313
CNAT_BADTEST ....... 0
CNAT_ISFULLCONE .... 1
CNAT_ISPAT ......... 0
CNAT_ISPATHOST ..... 0
CNAT_TRYPRESERVE ... 0
CNAT_PATINORDER .... 0
CNAT_BLOCKIP ....... 0
CNAT_BLOCKPORT ..... 0
CNAT_LOOPBACK ...... 1
135141.296977 Found 1 cameras:
135141.297266 iSight - 2857630788241996
135141.298244 selectedCamera GUID returned iSight
135141.314815 Microphone devices:
135141.315130 iSight Built-in
135141.315393 Line In
135141.315587 Digital In
135141.315817 Plantronics Headset
135141.316028 Selected mic: Plantronics Headset
135141.333663 Speaker devices:
135141.333834 283 Built-in Audio
135141.333872 278 Plantronics Headset
135141.351892 Selected speaker: Plantronics Headset
135141.376141 Selected microphone is Plantronics Headset.
135142.362598 Found 1 cameras:
135142.362776 iSight - 2857630788241996
135142.362853 selectedCamera GUID returned iSight
135142.378685 Microphone devices:
135142.379214 iSight Built-in
135142.379774 Line In
135142.380067 Digital In
135142.380288 Plantronics Headset
135142.380767 Selected mic: Plantronics Headset
135142.398028 Speaker devices:
135142.398181 283 Built-in Audio
135142.398216 278 Plantronics Headset
135142.415838 Selected speaker: Plantronics Headset
135142.428000 Selected microphone is Plantronics Headset.

is just hangs here in Terminal - I have to ctrl-c to get out.

---

Something is seriously wrong here if it's not even seeing my correct LAN IP. :/

-Mark

May 27, 2009 2:10 PM in response to Mark Lindsey

Hi Mark,

The Log of the Chat does not resolve the IP And therefore the port of the rip (Remote IP)

AS you are saying the LAN IP shown in the Terminal Log is "wrong" it would seem you have to spend some time resolving your LAN.

10:10 PM Wednesday; May 27, 2009

Please, if posting Logs, do not post any Log info after the line "Binary Images for iChat"

Mark Lindsey Author

Level 2

159 points

May 27, 2009 2:39 PM in response to Ralph-Johns-UK

Ralph,

That's just the problem. Before I followed all of your instructions to re-install iChat, it showed both IPs, the main, correct IP, and the other IP which is only used when I have Internet Sharing turned on (which it is not now).

There's nothing wrong with my LAN setup - there is some kind of corruption in the networking services here, which are now worse than before we started this thread.

Unless you can think of why iChat would be attempting to use the wrong IP when everything else in this system is functioning just fine (using the correct IP), then I guess I have no choice but to do a clean install of the OS.

-Mark

May 27, 2009 2:55 PM in response to Mark Lindsey

Hi,

iChat is very fussy.

It does not like it when it can see two connections to the Internet.
Of course there are several way to create this.

Internet sharing on creates a Loop either through Parallels or another device. I have seen an iPhone shared this way to get it on the Internet and cause problems.

I once read of someone who had a Still Firewire Camera that was identified with an IP that did the same even though Internet Sharing was not On but there were two active Networks in System Preferences > Network.

System Preferences > Network can only display one IP if one Network is active.
It is possible to join one network with Ethernet and another with th Airport card. Or as you seem to have had at one stage a Second Ethernet card.

Now Network Preferences can "hold" an IP if something is using it and can be something that a Restart of the computer will sort out.

If I was getting a Strange IP I would look into it.

As 192.168.xxx.xxx where the xxx can be anything between 0 (or 1) and 524 (or 255) and are common on LAN devices I would look to see if something else is giving that IP.
If Wifi, it could be a Neighbour.

It could be a VPN given IP.

It is late here and it may be a while before I post again.

10:55 PM Wednesday; May 27, 2009

Please, if posting Logs, do not post any Log info after the line "Binary Images for iChat"

May 28, 2009 1:31 PM in response to Mark Lindsey

OK, I found this thread by googling "qcpatch photobooth", so this solution is not specific to iChat or networking, but to the use of the camera. It was related enough that I thought it was worth a post. On a new Macbook pro, with a user directory migrated from a 10.4 machine, I had a problem where photobooth initially seemed to work, then failed after taking a picture and did not save the picture. I did basic old school mac Preferences troubleshooting to isolate the culprit, which turned out to be . . .

com.apple.dock.db
com.apple.dock.plist

I have no idea why the dock has any relation to photobooth, but I verified this solution forward & reverse. I reactivated these two prefs and found that the problem returned. So, if you don't mind setting up your dock again, deleting these two files might help with photobooth/ichat camera issues.

May 28, 2009 1:56 PM in response to jeo-at-mac.com

Hi,

It may be that the whole Database and therefore .plist are corrupt.

It could be that deleting Photo Booth form the Dock re-writes the two enough to then re-add Photo Booth.

A link to the place you found the info could help.

9:56 PM Thursday; May 28, 2009

Please, if posting Logs, do not post any Log info after the line "Binary Images for iChat"

May 28, 2009 2:36 PM in response to Ralph-Johns-UK

I didn't look up the answer anywhere. This was just trial and error . . . pulling out user prefs, then logging out & in & testing. . . like we used to do in the old classic mac os. I found the googlable material in the console, but that produced only a thread from 2007 & Tiger that was closed and was ultimately related to third party cameras and/or video apps. This thread here is the only other useful information I found, so I had to find the solution for myself. It turns out that your understanding of the issue was correct. I put back the "bad" dock files and verified the error, then removed photobooth from the dock and the issue was resolved. . . simply by removing the icon from the dock. Adding the icon back into the dock did not reintroduce the problem.

May 28, 2009 2:40 PM in response to jeo-at-mac.com

Hi,

Thanks for that, and the confirmation that it is something about the Photo Booth DOCK icon only.

10:40 PM Thursday; May 28, 2009

Please, if posting Logs, do not post any Log info after the line "Binary Images for iChat"

SIP Connection Errors