Sure. So, bluetooth is turned on, headset is paired, but not connected.
This was taken before patching.
$ pjsip-apps/bin/pjsua-i386-apple-darwin10.6.0
01:20:10.924 os_core_unix.c pjlib 1.8.10 for POSIX initialized
01:20:10.929 sip_endpoint.c Creating endpoint instance...
01:20:10.929 pjlib select() I/O Queue created (0x1008366d8)
01:20:10.929 sip_endpoint.c Module "mod-msg-print" registered
01:20:10.929 sip_transport. Transport manager created.
01:20:10.929 sip_endpoint.c Module "mod-pjsua-log" registered
01:20:10.929 sip_endpoint.c Module "mod-tsx-layer" registered
01:20:10.929 sip_endpoint.c Module "mod-stateful-util" registered
01:20:10.930 sip_endpoint.c Module "mod-ua" registered
01:20:10.930 sip_endpoint.c Module "mod-100rel" registered
01:20:10.930 sip_endpoint.c Module "mod-pjsua" registered
01:20:10.930 sip_endpoint.c Module "mod-invite" registered
01:20:10.969 coreaudio_dev. core audio initialized with 4 devices
01:20:10.969 coreaudio_dev. dev_id 0: Built-in Microphone (in=2, out=0) 44100Hz
01:20:10.969 coreaudio_dev. dev_id 1: Built-in Input (in=2, out=0) 44100Hz
01:20:10.972 coreaudio_dev. dev_id 2: Built-in Output (in=0, out=2) 48000Hz
01:20:10.974 coreaudio_dev. dev_id 3: 9xxPlantronics (in=1, out=1) 8000Hz
01:20:10.976 pa_dev.c PortAudio sound library initialized, status=0
01:20:10.976 pa_dev.c PortAudio host api count=1
01:20:10.976 pa_dev.c Sound device count=4
01:20:10.977 pjlib select() I/O Queue created (0x10082ac28)
01:20:10.986 sip_endpoint.c Module "mod-evsub" registered
01:20:10.986 sip_endpoint.c Module "mod-presence" registered
01:20:10.986 sip_endpoint.c Module "mod-mwi" registered
01:20:10.986 sip_endpoint.c Module "mod-refer" registered
01:20:10.986 sip_endpoint.c Module "mod-pjsua-pres" registered
01:20:10.986 sip_endpoint.c Module "mod-pjsua-im" registered
01:20:10.986 sip_endpoint.c Module "mod-pjsua-options" registered
01:20:10.986 pjsua_core.c 1 SIP worker threads created
01:20:10.987 pjsua_core.c pjsua version 1.8.10 for i386-apple-darwin10.6.0 initialized
01:20:10.987 sip_endpoint.c Module "mod-default-handler" registered
01:20:10.988 pjsua_core.c SIP UDP socket reachable at 192.168.1.3:5060
01:20:10.988 udp0x1004243c0 SIP UDP transport started, published address is 192.168.1.3:5060
01:20:10.988 pjsua_acc.c Account <sip:192.168.1.3:5060> added with id 0
01:20:10.988 tcplis:5060 SIP TCP listener ready for incoming connections at 192.168.1.3:5060
01:20:10.988 pjsua_acc.c Account <sip:192.168.1.3:5060;transport=TCP> added with id 1
01:20:10.989 pjsua_media.c RTP socket reachable at 192.168.1.3:4000
01:20:10.989 pjsua_media.c RTCP socket reachable at 192.168.1.3:4001
01:20:10.990 pjsua_media.c RTP socket reachable at 192.168.1.3:4002
01:20:10.990 pjsua_media.c RTCP socket reachable at 192.168.1.3:4003
01:20:10.991 pjsua_media.c RTP socket reachable at 192.168.1.3:4004
01:20:10.991 pjsua_media.c RTCP socket reachable at 192.168.1.3:4005
01:20:10.992 pjsua_media.c RTP socket reachable at 192.168.1.3:4006
01:20:10.992 pjsua_media.c RTCP socket reachable at 192.168.1.3:4007
01:20:10.992 sip_endpoint.c Module "mod-unsolicited-mwi" registered
>>>>
Account list:
[ 0] <sip:192.168.1.3:5060>: does not register
Online status: Online
*[ 1] <sip:192.168.1.3:5060;transport=TCP>: does not register
Online status: Online
Buddy list:
-none-
+=============================================================================+
| Call Commands: | Buddy, IM & Presence: | Account: |
| | | |
| m Make new call | +b Add new buddy .| +a Add new accnt |
| M Make multiple calls | -b Delete buddy | -a Delete accnt. |
| a Answer call | i Send IM | !a Modify accnt. |
| h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register |
| H Hold call | u Unsubscribe presence | ru Unregister |
| v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.|
| U send UPDATE | T Set online status | < Cycle prev ac.|
| ],[ Select next/prev call +--------------------------+-------------------+
| x Xfer call | Media Commands: | Status & Config: |
| X Xfer with Replaces | | |
| # Send RFC 2833 DTMF | cl List ports | d Dump status |
| * Send DTMF with INFO | cc Connect port | dd Dump detailed |
| dq Dump curr. call quality | cd Disconnect port | dc Dump config |
| | V Adjust audio Volume | f Save config |
| S Send arbitrary REQUEST | Cp Codec priorities | f Save config |
+------------------------------+--------------------------+-------------------+
| q QUIT L ReLoad sleep MS echo [0|1|txt] n: detect NAT type |
+=============================================================================+
You have 0 active call
>>> m
(You currently have 0 calls)
Buddy list:
-none-
Choices:
0 For current dialog.
-1 All 0 buddies in buddy list
[1 - 0] Select from buddy list
URL An URL
<Enter> Empty input (or 'q') to cancel
Make call: sip:localhost
01:20:18.560 pjsua_media.c Opening sound device PCM at 16000/1/20ms
01:20:20.603 coreaudio_dev. core audio stream stopped
01:20:20.604 pjsua_media.c Opening sound device PCM at 44100/1/20ms
01:20:22.635 coreaudio_dev. core audio stream stopped
01:20:22.636 pjsua_media.c Opening sound device PCM at 48000/1/20ms
01:20:24.666 coreaudio_dev. core audio stream stopped
01:20:24.666 pjsua_media.c Opening sound device PCM at 32000/1/20ms
01:20:26.698 coreaudio_dev. core audio stream stopped
01:20:26.698 pjsua_media.c Opening sound device PCM at 16000/1/20ms
01:20:28.728 coreaudio_dev. core audio stream stopped
01:20:28.729 pjsua_media.c Opening sound device PCM at 8000/1/20ms
01:20:28.731 ec0x100427350 AEC created, clock_rate=8000, channel=1, samples per frame=160, tail length=200 ms, latency=49 ms
01:20:45.691 coreaudio_dev. core audio stream stopped
01:20:45.691 pjsua_media.c Unable to open sound device: Unknown error 537310186 [status=537310186]
>>> q
01:20:51.757 pjsua_core.c Shutting down...
01:20:51.757 pjsua_pres.c Shutting down presence..
01:20:51.757 pjsua_media.c Shutting down media..
01:20:52.260 pa_dev.c PortAudio sound library shutting down..
01:20:53.264 pjsua_core.c Destroying...
01:20:53.265 sip_transactio Stopping transaction layer module
01:20:53.265 sip_endpoint.c Module "mod-default-handler" unregistered
01:20:53.265 sip_endpoint.c Module "mod-unsolicited-mwi" unregistered
01:20:53.265 sip_endpoint.c Module "mod-pjsua-options" unregistered
01:20:53.265 sip_endpoint.c Module "mod-pjsua-im" unregistered
01:20:53.265 sip_endpoint.c Module "mod-pjsua-pres" unregistered
01:20:53.265 sip_endpoint.c Module "mod-pjsua" unregistered
01:20:53.265 sip_endpoint.c Module "mod-stateful-util" unregistered
01:20:53.265 sip_endpoint.c Module "mod-refer" unregistered
01:20:53.265 sip_endpoint.c Module "mod-mwi" unregistered
01:20:53.265 sip_endpoint.c Module "mod-presence" unregistered
01:20:53.265 sip_endpoint.c Module "mod-evsub" unregistered
01:20:53.265 sip_endpoint.c Module "mod-invite" unregistered
01:20:53.265 sip_endpoint.c Module "mod-100rel" unregistered
01:20:53.265 sip_endpoint.c Module "mod-ua" unregistered
01:20:53.265 sip_transactio Transaction layer module destroyed
01:20:53.265 sip_endpoint.c Module "mod-tsx-layer" unregistered
01:20:53.265 sip_endpoint.c Module "mod-msg-print" unregistered
01:20:53.265 sip_endpoint.c Module "mod-pjsua-log" unregistered
01:20:53.266 tcplis:5060 SIP TCP listener destroyed
01:20:53.266 sip_endpoint.c Endpoint 0x10082b8a8 destroyed
01:20:53.266 pjsua_core.c PJSUA destroyed...
And this was after I patched it with r3398 and r3404.
$ pjsip-apps/bin/pjsua-i386-apple-darwin10.6.0
01:26:32.230 os_core_unix.c pjlib 1.8.10 for POSIX initialized
01:26:32.234 sip_endpoint.c Creating endpoint instance...
01:26:32.235 pjlib select() I/O Queue created (0x1008366d8)
01:26:32.235 sip_endpoint.c Module "mod-msg-print" registered
01:26:32.235 sip_transport. Transport manager created.
01:26:32.235 sip_endpoint.c Module "mod-pjsua-log" registered
01:26:32.235 sip_endpoint.c Module "mod-tsx-layer" registered
01:26:32.235 sip_endpoint.c Module "mod-stateful-util" registered
01:26:32.235 sip_endpoint.c Module "mod-ua" registered
01:26:32.236 sip_endpoint.c Module "mod-100rel" registered
01:26:32.236 sip_endpoint.c Module "mod-pjsua" registered
01:26:32.236 sip_endpoint.c Module "mod-invite" registered
01:26:32.273 coreaudio_dev. core audio detected 4 devices
01:26:32.274 coreaudio_dev. dev_id 0: Built-in Microphone (in=2, out=0) 44100Hz
01:26:32.274 coreaudio_dev. dev_id 1: Built-in Input (in=2, out=0) 44100Hz
01:26:32.276 coreaudio_dev. dev_id 2: Built-in Output (in=0, out=2) 48000Hz
01:26:32.278 coreaudio_dev. dev_id 3: 9xxPlantronics (in=1, out=1) 8000Hz
01:26:32.278 coreaudio_dev. core audio initialized
01:26:32.280 pa_dev.c PortAudio sound library initialized, status=0
01:26:32.280 pa_dev.c PortAudio host api count=1
01:26:32.280 pa_dev.c Sound device count=4
01:26:32.280 pjlib select() I/O Queue created (0x100868a28)
01:26:32.289 sip_endpoint.c Module "mod-evsub" registered
01:26:32.289 sip_endpoint.c Module "mod-presence" registered
01:26:32.289 sip_endpoint.c Module "mod-mwi" registered
01:26:32.289 sip_endpoint.c Module "mod-refer" registered
01:26:32.289 sip_endpoint.c Module "mod-pjsua-pres" registered
01:26:32.289 sip_endpoint.c Module "mod-pjsua-im" registered
01:26:32.289 sip_endpoint.c Module "mod-pjsua-options" registered
01:26:32.289 pjsua_core.c 1 SIP worker threads created
01:26:32.289 pjsua_core.c pjsua version 1.8.10 for i386-apple-darwin10.6.0 initialized
01:26:32.289 sip_endpoint.c Module "mod-default-handler" registered
01:26:32.290 pjsua_core.c SIP UDP socket reachable at 192.168.1.3:5060
01:26:32.290 udp0x100333290 SIP UDP transport started, published address is 192.168.1.3:5060
01:26:32.290 pjsua_acc.c Account <sip:192.168.1.3:5060> added with id 0
01:26:32.291 tcplis:5060 SIP TCP listener ready for incoming connections at 192.168.1.3:5060
01:26:32.291 pjsua_acc.c Account <sip:192.168.1.3:5060;transport=TCP> added with id 1
01:26:32.292 pjsua_media.c RTP socket reachable at 192.168.1.3:4000
01:26:32.292 pjsua_media.c RTCP socket reachable at 192.168.1.3:4001
01:26:32.293 pjsua_media.c RTP socket reachable at 192.168.1.3:4002
01:26:32.293 pjsua_media.c RTCP socket reachable at 192.168.1.3:4003
01:26:32.293 pjsua_media.c RTP socket reachable at 192.168.1.3:4004
01:26:32.293 pjsua_media.c RTCP socket reachable at 192.168.1.3:4005
01:26:32.294 pjsua_media.c RTP socket reachable at 192.168.1.3:4006
01:26:32.294 pjsua_media.c RTCP socket reachable at 192.168.1.3:4007
01:26:32.294 sip_endpoint.c Module "mod-unsolicited-mwi" registered
>>>>
Account list:
[ 0] <sip:192.168.1.3:5060>: does not register
Online status: Online
*[ 1] <sip:192.168.1.3:5060;transport=TCP>: does not register
Online status: Online
Buddy list:
-none-
+=============================================================================+
| Call Commands: | Buddy, IM & Presence: | Account: |
| | | |
| m Make new call | +b Add new buddy .| +a Add new accnt |
| M Make multiple calls | -b Delete buddy | -a Delete accnt. |
| a Answer call | i Send IM | !a Modify accnt. |
| h Hangup call (ha=all) | s Subscribe presence | rr (Re-)register |
| H Hold call | u Unsubscribe presence | ru Unregister |
| v re-inVite (release hold) | t ToGgle Online status | > Cycle next ac.|
| U send UPDATE | T Set online status | < Cycle prev ac.|
| ],[ Select next/prev call +--------------------------+-------------------+
| x Xfer call | Media Commands: | Status & Config: |
| X Xfer with Replaces | | |
| # Send RFC 2833 DTMF | cl List ports | d Dump status |
| * Send DTMF with INFO | cc Connect port | dd Dump detailed |
| dq Dump curr. call quality | cd Disconnect port | dc Dump config |
| | V Adjust audio Volume | f Save config |
| S Send arbitrary REQUEST | Cp Codec priorities | f Save config |
+------------------------------+--------------------------+-------------------+
| q QUIT L ReLoad sleep MS echo [0|1|txt] n: detect NAT type |
+=============================================================================+
You have 0 active call
>>> m
(You currently have 0 calls)
Buddy list:
-none-
Choices:
0 For current dialog.
-1 All 0 buddies in buddy list
[1 - 0] Select from buddy list
URL An URL
<Enter> Empty input (or 'q') to cancel
Make call: sip:localhost
01:26:37.517 pjsua_media.c Opening sound device PCM at 16000/1/20ms
01:26:37.526 ec0x1003388d0 AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=23 ms
01:26:50.933 pjsua_media.c Opening sound device PCM at 44100/1/20ms
01:26:50.941 ec0x100408ff0 AEC created, clock_rate=44100, channel=1, samples per frame=882, tail length=200 ms, latency=20 ms
01:27:03.655 pjsua_media.c Opening sound device PCM at 48000/1/20ms
01:27:03.659 ec0x10040a1c0 AEC created, clock_rate=48000, channel=1, samples per frame=960, tail length=200 ms, latency=20 ms
01:27:16.131 pjsua_media.c Opening sound device PCM at 32000/1/20ms
01:27:16.134 ec0x100409ee0 AEC created, clock_rate=32000, channel=1, samples per frame=640, tail length=200 ms, latency=20 ms
01:27:28.504 pjsua_media.c Opening sound device PCM at 16000/1/20ms
01:27:28.507 ec0x100407f30 AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=23 ms
01:27:44.224 pjsua_media.c Opening sound device PCM at 8000/1/20ms
01:27:44.225 ec0x100339750 AEC created, clock_rate=8000, channel=1, samples per frame=160, tail length=200 ms, latency=49 ms
01:27:59.479 pjsua_media.c Unable to open sound device: Unknown error 537310186 [status=537310186]
>>> q
01:28:08.789 pjsua_core.c Shutting down...
01:28:08.789 pjsua_pres.c Shutting down presence..
01:28:08.789 pjsua_media.c Shutting down media..
01:28:09.290 pa_dev.c PortAudio sound library shutting down..
01:28:10.297 pjsua_core.c Destroying...
01:28:10.297 sip_transactio Stopping transaction layer module
01:28:10.297 sip_endpoint.c Module "mod-default-handler" unregistered
01:28:10.297 sip_endpoint.c Module "mod-unsolicited-mwi" unregistered
01:28:10.297 sip_endpoint.c Module "mod-pjsua-options" unregistered
01:28:10.297 sip_endpoint.c Module "mod-pjsua-im" unregistered
01:28:10.297 sip_endpoint.c Module "mod-pjsua-pres" unregistered
01:28:10.297 sip_endpoint.c Module "mod-pjsua" unregistered
01:28:10.297 sip_endpoint.c Module "mod-stateful-util" unregistered
01:28:10.297 sip_endpoint.c Module "mod-refer" unregistered
01:28:10.297 sip_endpoint.c Module "mod-mwi" unregistered
01:28:10.297 sip_endpoint.c Module "mod-presence" unregistered
01:28:10.297 sip_endpoint.c Module "mod-evsub" unregistered
01:28:10.297 sip_endpoint.c Module "mod-invite" unregistered
01:28:10.297 sip_endpoint.c Module "mod-100rel" unregistered
01:28:10.297 sip_endpoint.c Module "mod-ua" unregistered
01:28:10.297 sip_transactio Transaction layer module destroyed
01:28:10.297 sip_endpoint.c Module "mod-tsx-layer" unregistered
01:28:10.297 sip_endpoint.c Module "mod-msg-print" unregistered
01:28:10.297 sip_endpoint.c Module "mod-pjsua-log" unregistered
01:28:10.298 tcplis:5060 SIP TCP listener destroyed
01:28:10.298 sip_endpoint.c Endpoint 0x10082b8a8 destroyed
01:28:10.298 pjsua_core.c PJSUA destroyed...
Alexei
On 16 Jan, 2011, at 19:27, Ming wrote:
> Hi Alexei,
>
> Could you attach us the complete log (if possible, before and after the patch)?
>
> Thanks,
> Ming
>
> On Sun, Jan 16, 2011 at 9:23 PM, Alexei Kuznetsov <eofster at gmail.com> wrote:
>> Applied the patch to the latest release (1.8.10), but the problem still exists.
>>
>> Make call: sip:localhost
>> 15:40:18.300 pjsua_media.c Opening sound device PCM at 16000/1/20ms
>> 15:40:18.310 ec0x10033c290 AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=23 ms
>> 15:40:33.768 pjsua_media.c Opening sound device PCM at 44100/1/20ms
>> 15:40:33.777 ec0x10033c9f0 AEC created, clock_rate=44100, channel=1, samples per frame=882, tail length=200 ms, latency=20 ms
>> 15:40:46.331 pjsua_media.c Opening sound device PCM at 48000/1/20ms
>> 15:40:46.334 ec0x10180eae0 AEC created, clock_rate=48000, channel=1, samples per frame=960, tail length=200 ms, latency=20 ms
>> 15:40:58.945 pjsua_media.c Opening sound device PCM at 32000/1/20ms
>> 15:40:58.949 ec0x10180c900 AEC created, clock_rate=32000, channel=1, samples per frame=640, tail length=200 ms, latency=20 ms
>> 15:41:11.401 pjsua_media.c Opening sound device PCM at 16000/1/20ms
>> 15:41:11.404 ec0x10180d9e0 AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=23 ms
>> 15:41:24.091 pjsua_media.c Opening sound device PCM at 8000/1/20ms
>> 15:41:24.092 ec0x10033c080 AEC created, clock_rate=8000, channel=1, samples per frame=160, tail length=200 ms, latency=49 ms
>> 15:41:36.564 pjsua_media.c Unable to open sound device: Unknown error 537310186 [status=537310186]
>>
>> I'm getting this when the headset is paired but not connected, and the bluetooth is turned on in the OS when pjsua launches. If bluetooth isn't turned on on pjsua launch, everything's fine.
>>
>>
>> And there is another thing. If bluetooth is turned on and the headset is paired and connected, pjsua exits with asserion, but my Cocoa app linked against the same binaries doesn't do so. Turns out that's because I'm setting sound IO explicitly, even to the default, built-in, devices. And if, when on the call, I'm switching to the headset from my app, it exits with the same assertion.
>>
>> [Made a call to sip:localhost]
>> From: <sip:192.168.1.3>
>> To: <sip:localhost>
>> Press a to answer or h to reject call
>> 16:05:06.292 pjsua_core.c RX 302 bytes Response msg 100/INVITE/cseq=25860 (rdata0x10085be28) from UDP 127.0.0.1:5060:
>> SIP/2.0 100 Trying
>> Via: SIP/2.0/UDP 192.168.1.3:5060;rport=5060;received=127.0.0.1;branch=z9hG4bKPj5VWwNGymDBwZDGDrNpbde-KWog829yoa
>> Call-ID: 2xwY0kLV0RV2goQbQe2N8QfjEJUAWtMH
>> From: <sip:192.168.1.3>;tag=mqjPHOUACJZkROKY3yhsItBRxEYBnzAL
>> To: <sip:localhost>
>> CSeq: 25860 INVITE
>> Content-Length: 0
>>
>>
>> --end msg--
>> 16:05:06.365 os_core_unix.c Info: possibly re-registering existing thread
>> Assertion failed: (nsamples < strm->resample_buf_size), function resample_callback, file ../src/pjmedia-audiodev/coreaudio_dev.c, line 747.
>> Abort trap
>>
>> Alexei
>>
>>
>> On 13 Jan, 2011, at 19:47, Ming wrote:
>>
>>> Hi Alexei,
>>>
>>> Currently, Mac's CoreAudio backend will use the first audio
>>> input/output device. I just committed the change for it to use the
>>> system's default audio input/output device instead in ticket #1196
>>> (http://trac.pjsip.org/repos/ticket/1196). Please let us know if there
>>> is any problem. Thanks.
>>>
>>> Regards,
>>> Ming
>>>
>>> On Thu, Jan 13, 2011 at 7:33 AM, Alexei Kuznetsov <eofster at gmail.com> wrote:
>>>> Hi,
>>>>
>>>> I'm on a Mac using pjsua 1.8.10. I have a bluetooth headset which is paired with the Mac, but is not being used. When I make a call pjsua tries to open an audio device. Nothing happens and pjsua retries to open the device again and again. Then standard dialog box from the OS appears that says that bluetooth audio failed and there is a button "Stop using headset". After pressing the button pjsua prints out the last two lines, and returns to its default state with no active calls. During all this, default sound IO in the OS are Internal Speakers and Internal Microphone.
>>>>
>>>> Make call: sip:1 at localhost
>>>> 02:17:10.833 pjsua_media.c Opening sound device PCM at 16000/1/20ms
>>>> 02:17:12.912 coreaudio_dev. core audio stream stopped
>>>> 02:17:12.913 pjsua_media.c Opening sound device PCM at 44100/1/20ms
>>>> 02:17:14.923 coreaudio_dev. core audio stream stopped
>>>> 02:17:14.923 pjsua_media.c Opening sound device PCM at 48000/1/20ms
>>>> 02:17:16.936 coreaudio_dev. core audio stream stopped
>>>> 02:17:16.936 pjsua_media.c Opening sound device PCM at 32000/1/20ms
>>>> 02:17:18.949 coreaudio_dev. core audio stream stopped
>>>> 02:17:18.949 pjsua_media.c Opening sound device PCM at 16000/1/20ms
>>>> 02:17:20.978 coreaudio_dev. core audio stream stopped
>>>> 02:17:20.978 pjsua_media.c Opening sound device PCM at 8000/1/20ms
>>>> 02:17:20.980 ec0x10180f5c0 AEC created, clock_rate=8000, channel=1, samples per frame=160, tail length=200 ms, latency=49 ms
>>>>
>>>> 02:17:41.588 coreaudio_dev. core audio stream stopped
>>>> 02:17:41.588 pjsua_media.c Unable to open sound device: Unknown error 537310186 [status=537310186]
>>>>
>>>>
>>>> Why could pjsua try to open currently unused bluetooth audio device that is also not the default device? Any help is very appreciated. Many Telephone.app users suffer from this behavior.
>>>>
>>>> Alexei
