SIP Connection Errors

Thanks Defcom & Ralph,

More info & answers...

- I have indeed tried this with Encryption on & off (just tried again to be sure - same exact results other than the expected "Encryption: No" report), to no avail.

- DSL modem does not route - it's just a basic ADSL with ethernet on the back (not even wireless). As well, my audio/video previously worked with this exact same setup, so that seems to point to a system OS/app issue.

- TC is set to NAT, but no port forwarding rules are setup (it's just wide open).

- OS Firewall is set to allow specific apps (both iChatAgent and iChat are set to allow. I've also tried everything with the firewall turned off, with same results.

- I have 4 systems here all on the same Wi-Fi via the TC, but have also tried with my G5-Quad connected via ethernet, with no luck.

- I've created a test user account on my G5 and have the same results, so this is apparently a root problem.

- I've removed all non-standard contextual-menu plugins, just to try and rule everything out, to no avail.

- I've blown out my entire system/user cache folders, but no help.

- Again, I can voice-chat in Skype without problems, and also voice-comm in an online game, which pretty much rules out a hardware issue.

I haven't been able to find anything that I felt confident in trashing, but are there any prefs or plists or anything else that I can blow out or re-install (short of the entire OS) that might reset iChat?

Thanks for any additional suggestions,

-Mark

Thanks Ralph,

The connection port is specified in Skype and it also has an "allow" rule in the OS firewall. Of course, with the nerfed Leopard firewall I can't be sure if that specific port is open, but I'm assuming so.

Yeah, let me rephrase regarding the DSL modem. I've not ever changed its configuration. I'm connecting to the modem from the TC via DHCP (the default configuration on this BroadMax HSA300A-309). Again, the bottom line here is that this exact setup was working before and I've not changed anything since. 🙂

The TC is setup with DHCP and all of my systems are on the same subnet.

No, I'm actually having this problem with my PowerBook also. The problem started at different times though. I just checked my daughter's eMac and her video-chat is still working just fine. She's using port 5190 also, so I'm going back to that port on the G5

I'll try the plist trashing next, but let me know if you have any other tricks up your sleeve and thanks a bunch for the help. 🙂

-Mark

Hi Ralph,

Thanks for the additional tips.

My TC is actually being issued an IP of 76.191.x.x from the DSL modem, and then I'm having the TC issue a range of IPs like 192.168.4.x - it's 4.x instead of 1.x because I used to run a VPN for my remote telecommuting. This setup worked in the past and again, it's working for my daughters machine on the same sub-net, so I'm pretty sure it's a local CPU problem.

I still need to try the plist trashing and see if that will reset my install or not. I'll post my results and will add you and give a holler next chance I get (in UK time). 😉

Cheers and thanks,

-Mark

Hi again,

Just an update... I tried to trash my iChat plist files (all of them), but I'm still having the same connection errors (and still no addy on the "To" line in the console report.

Now I'm at a total loss, short of reinstalling the OS. Perhaps I can just reinstall iChat and see if that helps, but I'm getting the feeling that the problem is something deeper in the network services.

I've also noticed the following errors in the system log, but I can't confirm if these existed before I trashed the plist files:

May 22 00:49:49 username iChat[9671]: * <QCNodeManager | namespace = "com.apple.QuartzComposer" | 335 nodes>: Patch with name "/backdrops" is missing
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create node of class "/backdrops" and identifier "(null)"
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create connection from ["Background_Motion" @ "Patch_7"] to ["inputImage proxy1" @ "Patch_5"]
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create connection from ["output" @ "Splitter_4"] to ["Pixel_Image" @ "Patch_7"]
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create connection from ["Background_Motion" @ "Patch_7"] to ["input" @ "Splitter_3"]
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create connection from ["Image WithAlpha" @ "Patch_7"] to ["input" @ "Splitter_6"]
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create connection from ["output" @ "Splitter_12"] to ["Resolution" @ "Patch_7"]
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create connection from ["Captured_Background" @ "Patch_7"] to ["input" @ "Splitter_2"]
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A1818C0 "(null)">:\nCannot create connection from ["output" @ "Splitter_7"] to ["Capture" @ "Patch_7"]
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A108DB0 "(null)">:\nState restoration failed on node "Patch_3" <QCPatch = 0x1A1818C0 "(null)">
May 22 00:49:49 username iChat[9671]: * Message from <QCPatch = 0x1A103190 "(null)">:\nState restoration failed on node "Patch_3" <QCPatch = 0x1A108DB0 "(null)">
May 22 00:49:49 username iChat[9671]: * State restoration failed on <QCPatch = 0x1A103190 "(null)">

This dump was generated when I tried to connect to the appleu3 test user on AIM.

Thanks for any other ideas.

-Mark

Message was edited by: Mark Lindsey - just a typo correction

Hi Ralph,

Yeah, that's the first thing I checked when I saw the /background error. All of the effects seem to work fine, other than the green-screen background, of course. PhotoBooth was not pre-installed on this CPU, but I manually installed it a long time ago, and yes, it works just fine. :/

Yeah, the DNS is working fine - it was manually added to my prefs a long time ago and everything seems to be in order still.

This is a really pervasive problem. 😟

Thanks,

-Mark

Okay, I followed the procedure and here are the results (it's a bit long):

---

Terminal Dump:

150023.281865 ER_AddFilter (0): Adding [username@127.0.0.1]->[0]
150023.282008 setLocalUserName: username@127.0.0.1
150023.285106 Creating a port mapping from 54897 to 16402...
150023.285972 Default video size is (640 x 480)
150023.286011 H264: (640 x 480) @ 30 fps
150023.286033 H264: (320 x 240) @ 30 fps
150023.286048 H264: (160 x 120) @ 30 fps
150023.286079 H263: (352 x 288) @ 30 fps
150023.286095 H263: (176 x 144) @ 30 fps
150023.286279 ######################################
150023.286297 # SoundDec_Create(81536 --> 393219) #
150023.286306 ######################################
150023.294685 Leaving SoundDec_Create
150023.295160 ### NEW STATE: to: VC_INIT, from: VC_INVALID
150023.295329 ### NEW STATE: to: VC_IDLE, from: VC_INIT
150023.295355 ++++++++ confStatusMapRemoveAll
150023.295368 ( ) -- mapUserIDToSecurityInfo cleared --
150023.295405 NATTraversal created
150023.295639 Bandwidth based on QT prefs = 1500
150023.333821 SNATMAP Configuration: snatmap.mac.com:5678
150023.550085 Selected microphone is the null device microphone.
150023.550308 vcCapabilitiesOfCPU returns: 0x00000fff
150023.550326 VCCapAudio
150023.550335 VCCapVideo
150023.550344 VCCapMultiAudio
150023.550352 VCCapMultiVideo
150023.550360 VCCapMultiAudioHost
150023.550369 VCCapMultiVideoHost
150023.550378 VCCapRDClient
150023.550387 VCCapRDServer
150023.550395 VCCapRecordAudio
150023.550404 VCCapRecordVideo
150023.550412 VCCapAuxVideoSend
150023.550420 VCCapAuxVideoRecv
150023.550643 BWD: GotBandwidth: 678235/2332127 (up/down) @[192.168.2.1:255.255.255.0] ••• note: this is the wrong sub-net •••
150023.550673 BWD bandwidth: 678 kbits up, 2332 kbits down.
150023.550683 videoNetworkCapable: 1 -- 3 max participants.
150023.550692 audioNetworkCapable: 1 -- 9 max participants.
150023.550701 Supports PCMU wideband for audio: 1 / video: 1
150023.550784 vcCapabilitiesOfNetwork returns: 0x00000fff
150023.550794 VCCapAudio
150023.550803 VCCapVideo
150023.550811 VCCapMultiAudio
150023.550819 VCCapMultiVideo
150023.550828 VCCapMultiAudioHost
150023.550837 VCCapMultiVideoHost
150023.550845 VCCapRDClient
150023.550853 VCCapRDServer
150023.550861 VCCapRecordAudio
150023.550871 VCCapRecordVideo
150023.550879 VCCapAuxVideoSend
150023.550887 VCCapAuxVideoRecv
150023.550895 vcCapabilities returns: 0x00000fff
150023.550905 VCCapAudio
150023.550913 VCCapVideo
150023.550921 VCCapMultiAudio
150023.550929 VCCapMultiVideo
150023.550937 VCCapMultiAudioHost
150023.550946 VCCapMultiVideoHost
150023.550954 VCCapRDClient
150023.550964 VCCapRDServer
150023.550972 VCCapRecordAudio
150023.550980 VCCapRecordVideo
150023.550988 VCCapAuxVideoSend
150023.550996 VCCapAuxVideoRecv
150023.552041 Found 1 cameras:
150023.552122 iSight - 2857630788241996
150023.564620 Microphone devices:
150023.564813 iSight Built-in
150023.565126 Line In
150023.565246 Digital In
150023.565345 Plantronics Headset
150023.565442 Soundflower (2ch)
150023.565533 Soundflower (16ch)
150023.565726 Selected microphone is the null device microphone.
150023.565906 vcCapabilitiesOfCPU returns: 0x00000fff
150023.565964 VCCapAudio
150023.565974 VCCapVideo
150023.565983 VCCapMultiAudio
150023.565992 VCCapMultiVideo
150023.566000 VCCapMultiAudioHost
150023.566009 VCCapMultiVideoHost
150023.566018 VCCapRDClient
150023.566026 VCCapRDServer
150023.566034 VCCapRecordAudio
150023.566042 VCCapRecordVideo
150023.566051 VCCapAuxVideoSend
150023.566059 VCCapAuxVideoRecv
150023.566332 BWD: GotBandwidth: 678235/2332127 (up/down) @[192.168.2.1:255.255.255.0]
150023.566362 BWD bandwidth: 678 kbits up, 2332 kbits down.
150023.566372 videoNetworkCapable: 1 -- 3 max participants.
150023.566380 audioNetworkCapable: 1 -- 9 max participants.
150023.566389 Supports PCMU wideband for audio: 1 / video: 1
150023.566457 vcCapabilitiesOfNetwork returns: 0x00000fff
150023.566468 VCCapAudio
150023.566476 VCCapVideo
150023.566484 VCCapMultiAudio
150023.566492 VCCapMultiVideo
150023.566500 VCCapMultiAudioHost
150023.566508 VCCapMultiVideoHost
150023.566516 VCCapRDClient
150023.566523 VCCapRDServer
150023.566531 VCCapRecordAudio
150023.566539 VCCapRecordVideo
150023.566547 VCCapAuxVideoSend
150023.566555 VCCapAuxVideoRecv
150023.566567 vcCapabilities returns: 0x00000fff
150023.566576 VCCapAudio
150023.566584 VCCapVideo
150023.566592 VCCapMultiAudio
150023.566600 VCCapMultiVideo
150023.566608 VCCapMultiAudioHost
150023.566616 VCCapMultiVideoHost
150023.566624 VCCapRDClient
150023.566632 VCCapRDServer
150023.566640 VCCapRecordAudio
150023.566648 VCCapRecordVideo
150023.566656 VCCapAuxVideoSend
150023.566664 VCCapAuxVideoRecv
150023.567317 Found 1 cameras:
150023.567379 iSight - 2857630788241996
150023.567901 selectCameraByGUID: 2857630788241996 returned 1
150023.579138 Microphone devices:
150023.579319 iSight Built-in
150023.579525 Line In
150023.579623 Digital In
150023.579722 Plantronics Headset
150023.579815 Soundflower (2ch)
150023.579908 Soundflower (16ch)
150023.580019 Selected mic: Plantronics Headset
150023.591614 Speaker devices:
150023.591750 289 Built-in Audio
150023.591884 284 Plantronics Headset
150023.591917 260 Soundflower (2ch)
150023.591936 257 Soundflower (16ch)
150023.604045 Selected speaker: Plantronics Headset
150023.944132 in callback
150023.944181 -sdRef 0x173cd3a0 ?
150023.944217 -flags = 00000000
150023.944317 -IFIndex en2 ?
150023.944327 -errorCode == 00000000
150023.944341 -publicAddr == 76.191.157.119
150023.944350 -proto == 16
150023.944359 -privPort == 16402
150023.944369 -publPort == 54897
150023.944378 -ttl == 3600
150023.945281 Selected microphone is Plantronics Headset.
150023.946260 Selected microphone is Plantronics Headset.
150023.947575 Selected microphone is Plantronics Headset.
150023.958520 Found 1 cameras:
150023.958689 iSight - 2857630788241996
150023.958790 selectedCamera GUID returned iSight
150023.970315 Microphone devices:
150023.970498 iSight Built-in
150023.970682 Line In
150023.970835 Digital In
150023.970934 Plantronics Headset
150023.971024 Soundflower (2ch)
150023.971114 Soundflower (16ch)
150023.971243 Selected mic: Plantronics Headset
150023.983722 Speaker devices:
150023.983837 289 Built-in Audio
150023.983884 284 Plantronics Headset
150023.983911 260 Soundflower (2ch)
150023.983931 257 Soundflower (16ch)
150023.997404 Selected speaker: Plantronics Headset
150024.023997 Selected microphone is Plantronics Headset.
150026.442180 Comm reporting flags: 00000102
150026.461751 CommNAT result:
External IP: ••• note: my WAN IP was shown here •••
External Ports: 35452, 35452, 35452
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
150026.823992 Found 1 cameras:
150026.824099 iSight - 2857630788241996
150026.824145 selectedCamera GUID returned iSight
150026.834652 Microphone devices:
150026.834827 iSight Built-in
150026.834947 Line In
150026.835040 Digital In
150026.835131 Plantronics Headset
150026.835219 Soundflower (2ch)
150026.835307 Soundflower (16ch)
150026.835413 Selected mic: Plantronics Headset
150026.845963 Speaker devices:
150026.846055 289 Built-in Audio
150026.846073 284 Plantronics Headset
150026.846088 260 Soundflower (2ch)
150026.846101 257 Soundflower (16ch)
150026.857139 Selected speaker: Plantronics Headset
150027.107029 Selected microphone is Plantronics Headset.



Video Chat Attempted:


150137.969820 Error -8971 from ICMImageDescriptionGetProperty.
150137.970438 Microphone: callback thread: STANDARD; EXTENDED timeshare: 0; PRECEDENCE importance: 17
150137.970825 Microphone: null device thread: STANDARD; EXTENDED timeshare: 0; PRECEDENCE importance: 17
150137.990561 Microphone devices:
150137.990634 Camera_HasAudio = 0
150137.990751 iSight Built-in
150137.990886 Line In
150137.990984 Digital In
150137.991081 Plantronics Headset
150137.991180 Soundflower (2ch)
150137.991274 Soundflower (16ch)
150137.991551 Selected mic: Plantronics Headset
150138.055392 VTPRecvProc thread(-259317760) started...
150138.055685 SIPManager init with protocol: 0.
150138.055952 2 interfaces found.
150138.056365 Start listening on 192.168.2.1:16402(en1) sock 100
150138.056460 Start listening on 192.168.4.2:16402(en2) sock 100
150138.056479 2 valid interfaces found.
150138.056649 TPListen on port 16402
150138.056853 Transport(UDP) Thread start...
150138.057902 selectedCamera returned iSight
150138.109808 vcCapabilitiesOfCPU returns: 0x00000fff
150138.109865 VCCapAudio
150138.109885 VCCapVideo
150138.109893 VCCapMultiAudio
150138.109904 VCCapMultiVideo
150138.109912 VCCapMultiAudioHost
150138.109920 VCCapMultiVideoHost
150138.109929 VCCapRDClient
150138.109937 VCCapRDServer
150138.109945 VCCapRecordAudio
150138.109954 VCCapRecordVideo
150138.109963 VCCapAuxVideoSend
150138.109971 VCCapAuxVideoRecv
150138.110184 BWD: GotBandwidth: 678235/2332127 (up/down) @[192.168.2.1:255.255.255.0]
150138.110216 BWD bandwidth: 678 kbits up, 2332 kbits down.
150138.110227 videoNetworkCapable: 1 -- 3 max participants.
150138.110235 audioNetworkCapable: 1 -- 9 max participants.
150138.110244 Supports PCMU wideband for audio: 1 / video: 1
150138.110277 vcCapabilitiesOfNetwork returns: 0x00000fff
150138.110287 VCCapAudio
150138.110295 VCCapVideo
150138.110303 VCCapMultiAudio
150138.110312 VCCapMultiVideo
150138.110320 VCCapMultiAudioHost
150138.110328 VCCapMultiVideoHost
150138.110336 VCCapRDClient
150138.110344 VCCapRDServer
150138.110352 VCCapRecordAudio
150138.110360 VCCapRecordVideo
150138.110368 VCCapAuxVideoSend
150138.110376 VCCapAuxVideoRecv
150138.110384 vcCapabilities returns: 0x00000fff
150138.110393 VCCapAudio
150138.110401 VCCapVideo
150138.110410 VCCapMultiAudio
150138.110418 VCCapMultiVideo
150138.110426 VCCapMultiAudioHost
150138.110435 VCCapMultiVideoHost
150138.110444 VCCapRDClient
150138.110452 VCCapRDServer
150138.110460 VCCapRecordAudio
150138.110469 VCCapRecordVideo
150138.110477 VCCapAuxVideoSend
150138.110485 VCCapAuxVideoRecv
150138.257313 VCDelegate conforms to VideoConferenceDelegate protocol.
150138.257457 ER_AddFilter (1): Adding [username@mac.com1]->[0]
150138.257488 setLocalUserName: username@mac.com1
150138.257531 ( ) setValidatedIdentity: [00000000]
2009-05-22 15:01:38.411 iChat[12320:1d403] * <QCNodeManager | namespace = "com.apple.QuartzComposer" | 335 nodes>: Patch with name "/backdrops" is missing
2009-05-22 15:01:38.412 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create node of class "/backdrops" and identifier "(null)"
2009-05-22 15:01:38.414 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create connection from ["Background_Motion" @ "Patch_7"] to ["inputImage proxy1" @ "Patch_5"]
150138.415700 candidate collection start : 76.132197
150138.416556 SNATMAP server [17.250.248.149:5678]
150138.416710 sent SNATMAP request for [192.168.2.1:16402]
150138.416743 sent SNATMAP request for [192.168.4.2:16402]
2009-05-22 15:01:38.416 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create connection from ["output" @ "Splitter_4"] to ["Pixel_Image" @ "Patch_7"]
2009-05-22 15:01:38.416 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create connection from ["Background_Motion" @ "Patch_7"] to ["input" @ "Splitter_3"]
2009-05-22 15:01:38.418 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create connection from ["Image WithAlpha" @ "Patch_7"] to ["input" @ "Splitter_6"]
2009-05-22 15:01:38.419 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create connection from ["output" @ "Splitter_12"] to ["Resolution" @ "Patch_7"]
2009-05-22 15:01:38.421 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create connection from ["Captured_Background" @ "Patch_7"] to ["input" @ "Splitter_2"]
2009-05-22 15:01:38.422 iChat[12320:1d403] * Message from <QCPatch = 0x193A6390 "(null)">:
Cannot create connection from ["output" @ "Splitter_7"] to ["Capture" @ "Patch_7"]
2009-05-22 15:01:38.425 iChat[12320:1d403] * Message from <QCPatch = 0x19509990 "(null)">:
State restoration failed on node "Patch_3" <QCPatch = 0x193A6390 "(null)">
2009-05-22 15:01:38.443 iChat[12320:1d403] * Message from <QCPatch = 0x19690290 "(null)">:
State restoration failed on node "Patch_3" <QCPatch = 0x19509990 "(null)">
150138.462824 Found 1 cameras:
150138.462930 iSight - 2857630788241996
150138.462990 selectedCamera returned iSight
2009-05-22 15:01:38.470 iChat[12320:1d403] * State restoration failed on <QCPatch = 0x19690290 "(null)">
150138.478532 Microphone devices:
150138.478594 Camera_HasAudio = 0
150138.478730 iSight Built-in
150138.478865 Line In
150138.479101 Digital In
150138.479284 Plantronics Headset
150138.479453 Soundflower (2ch)
150138.479614 Soundflower (16ch)
150138.480043 Selected mic: Plantronics Headset
150138.490918 Camera_HasAudio = 0
150138.496527 Speaker devices:
150138.496634 289 Built-in Audio
150138.496654 284 Plantronics Headset
150138.496671 260 Soundflower (2ch)
150138.496686 257 Soundflower (16ch)
150138.510026 Microphone devices:
150138.510097 Camera_HasAudio = 0
150138.510255 iSight Built-in
150138.510845 Line In
150138.511067 Digital In
150138.511174 Plantronics Headset
150138.511272 Soundflower (2ch)
150138.511363 Soundflower (16ch)
150138.512449 Selected mic: Plantronics Headset
150138.515688 Selected speaker: Plantronics Headset
150138.536942 Selected microphone is Plantronics Headset.
150138.613023 Camera_HasAudio = 0
150138.613306 Diffusion prefilter disabled
150138.613382 Temporal prefilter disabled
150138.613491 startPreview returned 0
150138.631295 Video size change from 0x0 to 640x480(1280)
150139.415830 ReXmit collection request for [192.168.2.1:16402]
150139.415903 ReXmit collection request for [192.168.4.2:16402]
150140.414973 ReXmit collection request for [192.168.2.1:16402]
150140.415037 ReXmit collection request for [192.168.4.2:16402]
150141.414107 ReXmit collection request for [192.168.2.1:16402]
150141.414173 ReXmit collection request for [192.168.4.2:16402]
150142.413236 ReXmit collection request for [192.168.2.1:16402]
150142.413301 ReXmit collection request for [192.168.4.2:16402]
150143.412367 ReXmit collection request for [192.168.2.1:16402]
150143.412433 ReXmit collection request for [192.168.4.2:16402]
150144.411427 candidate collection done : 82.128310
150144.411503 add LOCAL candidate [192.168.2.1:16402]
150144.411725 getDNSSDExternalIPListForInterface failed (80180020)
150144.411742 add LOCAL candidate [192.168.4.2:16402]
150144.411869 Adding [en2] to DNSSD ip port list:[76.191.157.119:54897]
150144.411886 add UPnP candidate [ ••• my WAN IP was here ••• :54897]
150144.412257 VCConnectionData for call id: 1 returns 0x17e5c430
150144.984869 vcCapabilitiesOfCPU returns: 0x00000fff
150144.984918 VCCapAudio
150144.984936 VCCapVideo
150144.984945 VCCapMultiAudio
150144.984955 VCCapMultiVideo
150144.984963 VCCapMultiAudioHost
150144.984972 VCCapMultiVideoHost
150144.984980 VCCapRDClient
150144.984988 VCCapRDServer
150144.984996 VCCapRecordAudio
150144.985004 VCCapRecordVideo
150144.985014 VCCapAuxVideoSend
150144.985022 VCCapAuxVideoRecv
150144.985226 BWD: GotBandwidth: 678235/2332127 (up/down) @[192.168.2.1:255.255.255.0]
150144.985257 BWD bandwidth: 678 kbits up, 2332 kbits down.
150144.985268 videoNetworkCapable: 1 -- 3 max participants.
150144.985276 audioNetworkCapable: 1 -- 9 max participants.
150144.985285 Supports PCMU wideband for audio: 1 / video: 1
150144.985319 vcCapabilitiesOfNetwork returns: 0x00000fff
150144.985329 VCCapAudio
150144.985338 VCCapVideo
150144.985345 VCCapMultiAudio
150144.985353 VCCapMultiVideo
150144.985361 VCCapMultiAudioHost
150144.985369 VCCapMultiVideoHost
150144.985377 VCCapRDClient
150144.985385 VCCapRDServer
150144.985393 VCCapRecordAudio
150144.985401 VCCapRecordVideo
150144.985409 VCCapAuxVideoSend
150144.985417 VCCapAuxVideoRecv
150144.985425 vcCapabilities returns: 0x00000fff
150144.985433 VCCapAudio
150144.985441 VCCapVideo
150144.985449 VCCapMultiAudio
150144.985457 VCCapMultiVideo
150144.985464 VCCapMultiAudioHost
150144.985473 VCCapMultiVideoHost
150144.985481 VCCapRDClient
150144.985489 VCCapRDServer
150144.985497 VCCapRecordAudio
150144.985581 VCCapRecordVideo
150144.985589 VCCapAuxVideoSend
150144.985597 VCCapAuxVideoRecv
150144.988478 prepareConnection - remote VCConnectionData: 13ea0e70, local VCConnectionData: 00af4600
150144.989205 ICEStartConnectivityCheck(id[local:1 remote:1] count[local:3 remote:1] candidate[local:0x19861A90 remote:0x17E87A20] pair[0x00B5F400]
150144.989406 connectivity check thread start...(-257720320)
150144.989605 *** Insert conn check events ***
150144.989658 event 192.168.4.2:16402->17.202.32.14:16402 expires 82.756534
150144.989678 event 192.168.2.1:16402->17.202.32.14:16402 expires 82.806534
150144.989692 event ••• my WAN IP here ••• :54897->17.202.32.14:16402 expires 82.856534
150144.991161 Invoke delegate method vcc:initiateConferenceStartedForParty:(appleu3test022)
150144.991291 ### NEW STATE: to: VC_SIGNALING, from: VC_IDLE
150144.991319 initiateConferenceCallUsingConnectionDataWithErrorInfoToUser - start
150144.991329 Callee connection data 377 bytes. Caller connection data 621 bytes.
150144.991430 ( )( )( ) ---- <nil> valid returned 0
150144.991466 =========== OpenPorts!
150144.991706 RTP/RTCP ports for PayloadType 1 are 16402 and 16402
150144.992099 RTP vfd (102) accepts pkt (0010)
150144.992116 RTP vfd (103) accepts pkt (0040)
150144.992184 RTP/RTCP ports for PayloadType 0 are 16402 and 16402
150144.992532 RTP vfd (104) accepts pkt (0008)
150144.992573 RTP vfd (105) accepts pkt (0020)
150145.022611 selectedCamera returned iSight
150145.026725 * Caller SDP *
150145.028436 v=0
o=systemname 0 0 IN %RTP-IP-AF% %RTP-IP%
s=username@mac.com1
c=IN %RTP-IP-AF% %RTP-IP%
b=AS:2147483647
t=0 0
a=hwi:288:4:2500
a=iChatEncryption:NO
m=audio %ARTP-PORT% RTP/AVP 110 121 12 3 0
a=rtcp:%ARTCP-PORT%
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:2321028496
m=video %VRTP-PORT% RTP/AVP 123 126 34
a=rtcp:%VRTCP-PORT%
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 %ARTCP-PORT% VIDEO %VRTCP-PORT%
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:616614415
150145.028482 <<<<<<<<<< Conference initiation API version 2 >>>>>>>>>
150145.033158 join connectivity check thread(-257720320)...
150145.138945 *** event (192.168.4.2:16402->17.202.32.14:16402) fired ***
150145.139279 BINDING_REQUEST to 17.202.32.14:16402 from 192.168.4.2:16402
150145.139297 OLD STATE(WAITING)->NEW STATE(TESTING)
150145.288347 *** event (192.168.2.1:16402->17.202.32.14:16402) fired ***
150145.288475 BINDING_REQUEST to 17.202.32.14:16402 from 192.168.2.1:16402
150145.288486 OLD STATE(WAITING)->NEW STATE(TESTING)
150145.437549 *** event (76.191.157.119:54897->17.202.32.14:16402) fired ***
150145.437991 BINDING_REQUEST to 17.202.32.14:16402 from 76.191.157.119:54897
150145.438009 OLD STATE(WAITING)->NEW STATE(TESTING)
150148.120998 ===== connectivity check retry =====
150148.121057 *** Insert conn check events ***
150148.121087 event 192.168.4.2:16402->17.202.32.14:16402 expires 85.888186
150148.121109 event 192.168.2.1:16402->17.202.32.14:16402 expires 85.938186
150148.121122 event ••• my WAN IP here ••• :54897->17.202.32.14:16402 expires 85.988186
150148.171114 *** event (192.168.4.2:16402->17.202.32.14:16402) fired ***
150148.171252 BINDING_REQUEST to 17.202.32.14:16402 from 192.168.4.2:16402
150148.171263 OLD STATE(TESTING)->NEW STATE(TESTING)
150148.320384 *** event (192.168.2.1:16402->17.202.32.14:16402) fired ***
150148.320512 BINDING_REQUEST to 17.202.32.14:16402 from 192.168.2.1:16402
150148.320524 OLD STATE(TESTING)->NEW STATE(TESTING)
150148.469628 *** event (76.191.157.119:54897->17.202.32.14:16402) fired ***
150148.469785 BINDING_REQUEST to 17.202.32.14:16402 from ••• my WAN IP here ••• :54897
150148.469878 OLD STATE(TESTING)->NEW STATE(TESTING)
150151.003651 (ICEConnCheck.c:200) connectivity check timed out...
150151.003757 (ICEConnCheck.c:553) RecvAndProcess failed (80150017)
150151.003782 (ICEConnCheck.c:635) Connectivity check returned (80150017)
150151.004049 connectivity check thread(-257720320) end...(80150017).
150151.004075 Retry using classic invitation sequence.
150151.004317 <<<<<<<<<< Conference initiation API version 1 >>>>>>>>>
150151.004544 [ipAndPortData length] = 40
150151.004560 Length is valid: YES
150151.004610 CALLEE: ifname=[en0], IP=[17.202.32.14:16402]
2009-05-22 15:01:51.004 iChat[12320:1e60b] IPAndPortList: (
{
ip = "17.202.32.14";
port = 16402;
}
)
150151.005766 SIPConnect start...
150151.005955 ER_AddFilter (2): Adding [appleu3test022]->[u0]
150151.006358 sent SNATMAP request for [192.168.4.2:16402]
150152.006429 ReXmit collection request for [192.168.4.2:16402]
150153.006523 ReXmit collection request for [192.168.4.2:16402]
150154.006620 ReXmit collection request for [192.168.4.2:16402]
150155.006743 ReXmit collection request for [192.168.4.2:16402]
150156.006865 ReXmit collection request for [192.168.4.2:16402]
150156.006911 (ICE.c:1665) ICEGetExtIPPorts timed out...
150156.006976 ER_AddFilter (3): Adding [192.168.4.2]->[lip]
150156.007029 ER_AddFilter (4): Adding [192-168-4-2]->[lip]
150156.007080 ER_AddFilter (5): Adding [17.202.32.14]->[rip]
150156.007123 ER_AddFilter (6): Adding [17-202-32-14]->[rip]
150156.007680 TAInviteClientProc Thread start...
150156.007752 ( )( )( )( )( ) TP IS BINARY? (0)
150156.008233 Send to 17.202.32.14:16402 [INVITE sip:user@17.202.32.14:16402 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.2:16402;branch=z9hG4bK007afc2a06252f59
Max-Forwards: 70
To: "appleu3test022" <sip:user@17.202.32.14:16402>
From: "marklindsey@mac.com1" <sip:user@192.168.4.2:16402>;tag=375832478
Call-ID: 2a2efa3c-471c-11de-b69d-fb5d2bc74012@192-168-4-2
CSeq: 1 INVITE
Contact: <sip:user@192.168.4.2:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 735

v=0
o=username 0 0 IN IP4 192.168.4.2
s=username@mac.com1
c=IN IP4 192.168.4.2
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:2321028496
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:616614415
]
150156.508398 ( )( )( )( )( ) TP IS BINARY? (0)
150156.508793 Send to 17.202.32.14:16402 [INVITE sip:user@17.202.32.14:16402 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.2:16402;branch=z9hG4bK007afc2a06252f59
Max-Forwards: 70
To: "appleu3test022" <sip:user@17.202.32.14:16402>
From: "username@mac.com1" <sip:user@192.168.4.2:16402>;tag=375832478
Call-ID: 2a2efa3c-471c-11de-b69d-fb5d2bc74012@192-168-4-2
CSeq: 1 INVITE
Contact: <sip:user@192.168.4.2:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 735

v=0
o=username 0 0 IN IP4 192.168.4.2
s=username@mac.com1
c=IN IP4 192.168.4.2
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:2321028496
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:616614415
]
150157.508968 ( )( )( )( )( ) TP IS BINARY? (0)
150157.509378 Send to 17.202.32.14:16402 [INVITE sip:user@17.202.32.14:16402 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.2:16402;branch=z9hG4bK007afc2a06252f59
Max-Forwards: 70
To: "appleu3test022" <sip:user@17.202.32.14:16402>
From: "username@mac.com1" <sip:user@192.168.4.2:16402>;tag=375832478
Call-ID: 2a2efa3c-471c-11de-b69d-fb5d2bc74012@192-168-4-2
CSeq: 1 INVITE
Contact: <sip:user@192.168.4.2:16402>;isfocus
User-Agent: Viceroy 1.3
Content-Type: application/sdp
Content-Length: 735

v=0
o=username 0 0 IN IP4 192.168.4.2
s=username@mac.com1
c=IN IP4 192.168.4.2
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:2321028496
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:616614415
]
150158.007683 Dialog(CREATED) Match(STATUS) [2a2efa3c-471c-11de-b69d-fb5d2bc74012@192-168-4-2]=[2a2efa3c-471c-11de-b69d-fb5 d2bc74012@192-168-4-2], [375832478]=[375832478]
150158.007793 TAInviteClientProc Thread end(800B0017)...
150158.008028 SIPConnect stop(900A0015)...
150158.008053 SIP connect returned 21
150158.008563 SIPCloseCall start...
150158.008586 (SIP.c:3666) Cannot find call ID(2)
150158.008623 SIPCloseCall stop(800C0016)...
150158.008698 ### NEW STATE: to: VC_IDLE, from: VC_SIGNALING
150158.008742 ++++++++ confStatusMapRemoveAll
150158.008755 ( ) -- mapUserIDToSecurityInfo cleared --
150158.008788 Preparing to send VCInitiateConferenceCompleted
150158.008839 Invoke delegate method vcc:initiateConferenceCompletedForParty:(appleu3test022) - retcode: -8
150158.010409 vcCapabilitiesOfCPU returns: 0x00000fff
150158.010462 VCCapAudio
150158.010471 VCCapVideo
150158.010479 VCCapMultiAudio
150158.010488 VCCapMultiVideo
150158.010496 VCCapMultiAudioHost
150158.010504 VCCapMultiVideoHost
150158.010512 VCCapRDClient
150158.010521 VCCapRDServer
150158.010529 VCCapRecordAudio
150158.010537 VCCapRecordVideo
150158.010546 VCCapAuxVideoSend
150158.010555 VCCapAuxVideoRecv
150158.010809 BWD: GotBandwidth: 678235/2332127 (up/down) @[192.168.2.1:255.255.255.0]
150158.010843 BWD bandwidth: 678 kbits up, 2332 kbits down.
150158.010853 videoNetworkCapable: 1 -- 3 max participants.
150158.010862 audioNetworkCapable: 1 -- 9 max participants.
150158.010871 Supports PCMU wideband for audio: 1 / video: 1
150158.010908 vcCapabilitiesOfNetwork returns: 0x00000fff
150158.010917 VCCapAudio
150158.010925 VCCapVideo
150158.010933 VCCapMultiAudio
150158.010942 VCCapMultiVideo
150158.010950 VCCapMultiAudioHost
150158.010958 VCCapMultiVideoHost
150158.010966 VCCapRDClient
150158.010974 VCCapRDServer
150158.010981 VCCapRecordAudio
150158.010989 VCCapRecordVideo
150158.010997 VCCapAuxVideoSend
150158.011005 VCCapAuxVideoRecv
150158.011013 vcCapabilities returns: 0x00000fff
150158.011022 VCCapAudio
150158.011030 VCCapVideo
150158.011038 VCCapMultiAudio
150158.011046 VCCapMultiVideo
150158.011053 VCCapMultiAudioHost
150158.011063 VCCapMultiVideoHost
150158.011073 VCCapRDClient
150158.011081 VCCapRDServer
150158.011089 VCCapRecordAudio
150158.011097 VCCapRecordVideo
150158.011106 VCCapAuxVideoSend
150158.011113 VCCapAuxVideoRecv
150158.012063 external IPInfo collection start : 95.729551
150158.012114 SNATMAP server [17.250.248.149:5678]
150158.012205 sent SNATMAP request for [192.168.2.1:16402]
150158.012257 sent SNATMAP request for [192.168.4.2:16402]
150159.011328 ReXmit collection request for [192.168.2.1:16402]
150159.011392 ReXmit collection request for [192.168.4.2:16402]
150200.010461 ReXmit collection request for [192.168.2.1:16402]
150200.010534 ReXmit collection request for [192.168.4.2:16402]
150201.009602 ReXmit collection request for [192.168.2.1:16402]
150201.009682 ReXmit collection request for [192.168.4.2:16402]
150202.008773 ReXmit collection request for [192.168.2.1:16402]
150202.008848 ReXmit collection request for [192.168.4.2:16402]
150203.007927 ReXmit collection request for [192.168.2.1:16402]
150203.008001 ReXmit collection request for [192.168.4.2:16402]
150204.006997 external IPInfo collection done : 101.724940
150204.007062 no external IPPORT found
150204.007079 no external IPPORT found
150204.007093 LOCAL: ifname=[en1], IPPORT=[192.168.2.1:16402]
150204.007104 LOCAL: ifname=[en2], IPPORT=[192.168.4.2:16402]
150204.007114 LOCAL: ifname=[en1~], IPPORT=[63.87.253.254:16402]
150204.007125 LOCAL: ifname=[en2~], IPPORT=[63.87.251.253:16402]
150204.007243 Obtained 160 bytes of local IP and port data (4 entries). Remote data was 0 bytes (0 entries).
150214.409882 MULTIPOINT VC_API OVERRIDE: endConference
150214.409953 userIDToCallIDMap count = 0
150214.576358 Connection closed from the child process
150214.581398 cancelPreview returned 0
150214.583411 Found 1 cameras:
150214.583542 iSight - 2857630788241996
150214.583613 selectedCamera GUID returned iSight
150214.595144 Microphone devices:
150214.595359 iSight Built-in
150214.595626 Line In
150214.595728 Digital In
150214.595830 Plantronics Headset
150214.595927 Soundflower (2ch)
150214.596021 Soundflower (16ch)
150214.596152 Selected mic: Plantronics Headset
150214.608913 Speaker devices:
150214.608995 289 Built-in Audio
150214.609015 284 Plantronics Headset
150214.609031 260 Soundflower (2ch)
150214.609045 257 Soundflower (16ch)
150214.622345 Selected speaker: Plantronics Headset
150214.648051 Selected microphone is Plantronics Headset.
150218.540296 Get error report.
150218.540361 Filters: 7/20
150218.540376 - [username@127.0.0.1](18) ==> [0](1)
150218.540386 - [username@mac.com1](20) ==> [0](1)
150218.540397 - [appleu3test022](14) ==> [u0](2)
150218.540407 - [192.168.4.2](11) ==> [lip](3)
150218.540417 - [192-168-4-2](11) ==> [lip](3)
150218.540427 - [17.202.32.14](12) ==> [rip](3)
150218.540436 - [17-202-32-14](12) ==> [rip](3)
150232.691553 cancelPreview returned 0

###


Thanks,

-Mark

Hi Ralph,

Yeas, anything with ••• was my edit - sorry I forgot to specify. What I meant by "wrong" IP was that my LAN is setup on 192.168.4.x and iChat seemed to be trying to also connect on 192.168.2.x. I think this is the IP that the second ethernet port on my G5 uses when Internet Sharing is turned on, although it's currently turned off.

My Quicktime streaming pref is set to "1.5Mbps T1/Intranet/LAN" as opposed to the "Intranet/LAN" setting.

Honestly, I can't remember what I was using the Soundflower audio driver for - it was probably required for some software that I'm no longer using. I'll look into removing it, but again, this exact setup has previously worked just fine, so I don't think that's the problem.

en2 is currently assigned to my USB WiFi adapter, which is my main wireless connection to the TC.

en1 is assigned to Internet sharing (for a Win laptop), but it's currently turned off. Nothing else is plugged in (i.e. nothing in my #1 en0 ethernet port).

Remember, I've tried this setup with the firewall turned off, so I'm not sure how I can DMZ it any further than that.

I'll see if removing the Soundflower changes anything.

Thanks,

-Mark

I just removed Soundflower and restarted, but I'm still getting -7 error on the apple 02 test user and -8 on the 03 test user. 😟