This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

no voice in pjsip

Other Parts Discussed in Thread: AM1808, OMAP-L138, DA8XX

Hi,

I use the TLV320AIC3X codec on am335x processor. In this system , I successfully run the aplay and arecord application. But I hear
short voice and then no sound when trying the pjsua application in pjsip library. In this situation, I check the status value via
cat /proc/asound/card0/pcm0p/sub0/status , I see the result that is below.

    state: PREPARED
    owner_pid   : 801
    trigger_time: 1305732022.264972625
    tstamp      : 1305732051.945609499
    delay       : 0
    avail       : 0
    avail_max   : 0
    -----
    hw_ptr      : 0
    appl_ptr    : 5120

    I also check the values of proc/asound/card0/pcm0c/sub0/status for controlling the capture status. I get the same values with
    cat /proc/asound/card0/pcm0p/sub0/status in owner_pid and state lines.On the other hand, I see the state: RUNNING in
    /proc/asound/card0/pcm0p/sub0/status and pcm0c when I run the aplay and arecord application at the same time. In addition, values of
    owner_pid is different for capture and playback status. I think that reason of not working the pjsua is that one process does not
    capture and playback at the same time. Is it true?


    If it is true, what the method of only running the playback or capture in pjsip?

  • Hi User4522511,

    I suggest you to search in the kernel log ("dmesg" command output) for some errors or suspicious messages related to the TVL320AIC3X codec. If there is nothing related to the codec it is very possible the issue to be caused by the pjsip application.

    BR
    Tsvetolin Shulev
  • hi,

    I can not find a error that is related the codec in dmesg output.it is below. But output of pjsua in arm
    is same the pc output that is succesfully run. In this situation, Where should I look for solutions?
    output of pjsua also is below.

    Thank you for your answers.


    root@hawkboard:~# dmesg
    [ 0.000000] Linux version 2.6.36-rc5-11 (gcc version 4.5.2 (Sourcery G++ Lite 2011.03-41) ) #16 PREEMPT Mon Mar 2 09:10:16 EET 2015
    [ 0.000000] CPU: ARM926EJ-S [41069265] revision 5 (ARMv5TEJ), cr=00053177
    [ 0.000000] CPU: VIVT data cache, VIVT instruction cache
    [ 0.000000] Machine: DaVinci DA850/AM1808
    [ 0.000000] Memory policy: ECC disabled, Data cache writeback
    [ 0.000000] DaVinci da850/omap-l138 variant 0x1
    [ 0.000000] On node 0 totalpages: 32768
    [ 0.000000] free_area_init_node: node 0, pgdat c04642b4, node_mem_map c04c7000
    [ 0.000000] DMA zone: 256 pages used for memmap
    [ 0.000000] DMA zone: 0 pages reserved
    [ 0.000000] DMA zone: 32512 pages, LIFO batch:7
    [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512
    [ 0.000000] Kernel command line: console=ttyS2,115200n8 ubi.mtd=filesystem,2048 root=ubi0:hawkboard-rootfs rootflags=sync rootfstype=ubifs
    [ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
    [ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
    [ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
    [ 0.000000] Memory: 128MB = 128MB total
    [ 0.000000] Memory: 125056k/125056k available, 6016k reserved, 0K highmem
    [ 0.000000] Virtual kernel memory layout:
    [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
    [ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
    [ 0.000000] DMA : 0xff000000 - 0xffe00000 ( 14 MB)
    [ 0.000000] vmalloc : 0xc8800000 - 0xfea00000 ( 866 MB)
    [ 0.000000] lowmem : 0xc0000000 - 0xc8000000 ( 128 MB)
    [ 0.000000] modules : 0xbf000000 - 0xc0000000 ( 16 MB)
    [ 0.000000] .init : 0xc0008000 - 0xc0049000 ( 260 kB)
    [ 0.000000] .text : 0xc0049000 - 0xc0443000 (4072 kB)
    [ 0.000000] .data : 0xc0444000 - 0xc04668a0 ( 139 kB)
    [ 0.000000] SLUB: Genslabs=11, HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
    [ 0.000000] Hierarchical RCU implementation.
    [ 0.000000] RCU-based detection of stalled CPUs is disabled.
    [ 0.000000] Verbose stalled-CPUs detection is disabled.
    [ 0.000000] NR_IRQS:245
    [ 0.000000] Console: colour dummy device 80x30
    [ 0.000000] Calibrating delay loop... 149.50 BogoMIPS (lpj=747520)
    [ 0.210000] pid_max: default: 32768 minimum: 301
    [ 0.210000] Mount-cache hash table entries: 512
    [ 0.210000] CPU: Testing write buffer coherency: ok
    [ 0.220000] id 0 chip base 0:0 ngpio 32
    [ 0.220000] status 0 id 32
    [ 0.220000] id 0 chip base 32:32 ngpio 32
    [ 0.220000] status 0 id 64
    [ 0.220000] id 0 chip base 64:64 ngpio 32
    [ 0.220000] status 0 id 96
    [ 0.220000] id 0 chip base 96:96 ngpio 32
    [ 0.220000] status 0 id 128
    [ 0.220000] id 0 chip base 128:128 ngpio 16
    [ 0.220000] status 0 id 144
    [ 0.220000]
    [ 0.220000] DaVinci: 144 gpio irqs
    [ 0.220000] NET: Registered protocol family 16
    [ 0.270000] UKS board initialization started
    [ 0.280000] LCD initialization started
    [ 0.280000] uks_lcd_hw_init done
    [ 0.280000] LCD initialization complated
    [ 0.280000] UKS board initialization complated
    [ 0.350000] bio: create slab <bio-0> at 0
    [ 0.360000] SCSI subsystem initialized
    [ 0.370000] i2c i2c-1: Failed to register i2c client adp5588-gpio at 0x34 (-16)
    [ 0.370000] i2c i2c-1: Can't create device at 0x34
    [ 0.380000] Advanced Linux Sound Architecture Driver Version 1.0.23.
    [ 0.380000] register_netdev 0
    [ 0.380000] Switching to clocksource timer0_1
    [ 0.400000] NET: Registered protocol family 2
    [ 0.410000] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
    [ 0.410000] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
    [ 0.410000] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
    [ 0.410000] TCP: Hash tables configured (established 4096 bind 4096)
    [ 0.410000] TCP reno registered
    [ 0.410000] UDP hash table entries: 256 (order: 0, 4096 bytes)
    [ 0.410000] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
    [ 0.410000] NET: Registered protocol family 1
    [ 0.410000] RPC: Registered udp transport module.
    [ 0.410000] RPC: Registered tcp transport module.
    [ 0.410000] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [ 0.420000] pinmux_probe: 0x1c14120 len=81
    [ 0.420000] davinci_pinmux davinci_pinmux.0: pinmux device davinci_pinmux.0 registered
    [ 0.430000] msgmni has been set to 244
    [ 0.440000] io scheduler noop registered (default)
    [ 0.440000] da8xx_lcdc da8xx_lcdc.0: GLCD: Found Sharp_LK043T1DG01 panel
    [ 0.450000] Console: switching to colour frame buffer device 60x34
    [ 0.480000] Serial: 8250/16550 driver, 3 ports, IRQ sharing disabled
    [ 0.510000] serial8250.0: ttyS0 at MMIO 0x1c42000 (irq = 25) is a 16550A
    [ 0.530000] serial8250.0: ttyS1 at MMIO 0x1d0c000 (irq = 53) is a 16550A
    [ 0.550000] serial8250.0: ttyS2 at MMIO 0x1d0d000 (irq = 61) is a 16550A
    [ 0.970000] console [ttyS2] enabled
    [ 0.980000] brd: module loaded
    [ 0.990000] at24 1-0050: 8192 byte 24c64 EEPROM (writable)
    [ 0.990000] ad5301: DAC value = 1680:51657
    [ 1.010000] NAND device: Manufacturer ID: 0x2c, Chip ID: 0xf1 (Micron NAND 128MiB 3,3V 8-bit)
    [ 1.020000] Bad block table not found for chip 0
    [ 1.020000] Bad block table not found for chip 0
    [ 1.030000] Scanning device for bad blocks
    [ 1.120000] Bad block table written to 0x000007fe0000, version 0x01
    [ 1.120000] Bad block table written to 0x000007fc0000, version 0x01
    [ 1.120000] Creating 6 MTD partitions on "davinci_nand.1":
    [ 1.130000] 0x000000000000-0x000000020000 : "u-boot env"
    [ 1.140000] 0x000000020000-0x000000040000 : "UBL"
    [ 1.150000] 0x000000040000-0x0000000c0000 : "u-boot"
    [ 1.170000] 0x000000200000-0x000000600000 : "kernel"
    [ 1.180000] 0x000000600000-0x000000620000 : "MAC Address"
    [ 1.180000] Read MAC addr from EEPROM: 55:42:49:23:01:00
    [ 1.200000] 0x000000620000-0x000008000000 : "filesystem"
    [ 1.210000] davinci_nand davinci_nand.1: controller rev. 2.5
    [ 1.220000] UBI: attaching mtd5 to ubi0
    [ 1.220000] UBI DBG (pid 1): io_init: min_io_size 2048
    [ 1.220000] UBI DBG (pid 1): io_init: hdrs_min_io_size 2048
    [ 1.220000] UBI DBG (pid 1): io_init: ec_hdr_alsize 2048
    [ 1.220000] UBI DBG (pid 1): io_init: vid_hdr_alsize 2048
    [ 1.220000] UBI DBG (pid 1): io_init: vid_hdr_offset 2048
    [ 1.220000] UBI DBG (pid 1): io_init: vid_hdr_aloffset 2048
    [ 1.220000] UBI DBG (pid 1): io_init: vid_hdr_shift 0
    [ 1.220000] UBI DBG (pid 1): io_init: leb_start 4096
    [ 1.220000] UBI DBG (pid 1): io_init: max_erroneous 97
    [ 1.220000] UBI: physical eraseblock size: 131072 bytes (128 KiB)
    [ 1.230000] UBI: logical eraseblock size: 126976 bytes
    [ 1.230000] UBI: smallest flash I/O unit: 2048
    [ 1.240000] UBI: VID header offset: 2048 (aligned 2048)
    [ 1.240000] UBI: data offset: 4096
    [ 2.320000] UBI DBG (pid 1): ubi_scan: scanning is finished
    [ 2.320000] UBI: max. sequence number: 36392
    [ 2.340000] UBI warning: print_rsvd_warning: cannot reserve enough PEBs for bad PEB handling, reserved 8, need 9
    [ 2.360000] UBI: attached mtd5 to ubi0
    [ 2.360000] UBI: MTD device name: "filesystem"
    [ 2.370000] UBI: MTD device size: 121 MiB
    [ 2.380000] UBI: number of good PEBs: 971
    [ 2.380000] UBI: number of bad PEBs: 4
    [ 2.380000] UBI: max. allowed volumes: 128
    [ 2.390000] UBI: wear-leveling threshold: 4096
    [ 2.390000] UBI: number of internal volumes: 1
    [ 2.400000] UBI: number of user volumes: 1
    [ 2.400000] UBI: available PEBs: 0
    [ 2.410000] UBI: total number of reserved PEBs: 971
    [ 2.410000] UBI: number of PEBs reserved for bad PEB handling: 8
    [ 2.420000] UBI: max/mean erase counter: 117/37
    [ 2.420000] UBI: image sequence number: 159481198
    [ 2.430000] UBI: background thread "ubi_bgt0d" started, PID 257
    [ 2.440000] davinci_emac_probe: using random MAC addr: 46:ae:67:49:71:42
    [ 2.450000] register_netdev 0
    [ 2.510000] davinci_mdio davinci_mdio.0: davinci mdio revision 1.5
    [ 2.510000] davinci_mdio davinci_mdio.0: detected phy mask e0ffff
    [ 2.550000] davinci_mdio.0: probed
    [ 2.550000] davinci_mdio davinci_mdio.0: phy[16]: device 0:10, driver unknown
    [ 2.560000] davinci_mdio davinci_mdio.0: phy[17]: device 0:11, driver unknown
    [ 2.570000] davinci_mdio davinci_mdio.0: phy[18]: device 0:12, driver unknown
    [ 2.580000] davinci_mdio davinci_mdio.0: phy[19]: device 0:13, driver unknown
    [ 2.580000] davinci_mdio davinci_mdio.0: phy[20]: device 0:14, driver unknown
    [ 2.590000] davinci_mdio davinci_mdio.0: phy[24]: device 0:18, driver unknown
    [ 2.600000] davinci_mdio davinci_mdio.0: phy[25]: device 0:19, driver unknown
    [ 2.600000] davinci_mdio davinci_mdio.0: phy[26]: device 0:1a, driver unknown
    [ 2.610000] davinci_mdio davinci_mdio.0: phy[27]: device 0:1b, driver unknown
    [ 2.620000] davinci_mdio davinci_mdio.0: phy[28]: device 0:1c, driver unknown
    [ 2.630000] davinci_mdio davinci_mdio.0: phy[29]: device 0:1d, driver unknown
    [ 2.630000] davinci_mdio davinci_mdio.0: phy[30]: device 0:1e, driver unknown
    [ 2.640000] davinci_mdio davinci_mdio.0: phy[31]: device 0:1f, driver unknown
    [ 2.650000] dm9000 Ethernet Driver, V1.31
    [ 2.660000] mice: PS/2 mouse device common for all mice
    [ 2.660000] input: adp5588 as /devices/platform/i2c_davinci.1/i2c-1/1-0034/input/input0
    [ 2.670000] evbug.c: Connected device: input0 (adp5588 at adp5588/input0)
    [ 2.680000] adp5588 1-0034: Rev.4 keypad, irq 152
    [ 2.690000] i2c /dev entries driver
    [ 2.710000] No device for DAI tlv320aic3x
    [ 2.720000] asoc: tlv320aic3x <-> davinci-i2s mapping ok
    [ 2.740000] ALSA device list:
    [ 2.750000] #0: DA850/OMAP-L138 EVM (tlv320aic3x)
    [ 2.750000] TCP cubic registered
    [ 2.760000] NET: Registered protocol family 17
    [ 2.760000] mv_netlink_init initializing marvell netlink socket
    [ 2.770000] Distributed Switch Architecture driver version 0.1
    [ 2.770000] eth0[0]: detected a Marvell 88E6065 switch
    [ 2.820000] devId:101
    [ 2.820000] gstpSetPortState Called.
    [ 2.830000] OK.
    [ 2.830000] gstpSetPortState Called.
    [ 2.830000] OK.
    [ 2.830000] gstpSetPortState Called.
    [ 2.840000] OK.
    [ 2.840000] gstpSetPortState Called.
    [ 2.840000] OK.
    [ 2.840000] gstpSetPortState Called.
    [ 2.850000] OK.
    [ 2.850000] gstpSetPortState Called.
    [ 2.850000] OK.
    [ 2.860000] gsysSetCPUPort Called.
    [ 2.860000] OK.
    [ 2.860000] gsysSetRsvd2Cpu Called.
    [ 2.860000] OK.
    [ 2.870000] sysEnable Called.
    [ 2.870000] OK.
    [ 2.870000] Failed.
    [ 2.880000] gvtuFindVidEntry returned failed--> 13 .
    [ 2.880000] no entry found in database vid=2
    [ 2.890000] entry found in database vid=2
    [ 2.890000] Failed.
    [ 2.890000] gvtuFindVidEntry returned failed--> 13 .
    [ 2.900000] no entry found in database vid=3
    [ 2.900000] entry found in database vid=3
    [ 2.910000] Failed.
    [ 2.910000] gvtuFindVidEntry returned failed--> 13 .
    [ 2.920000] no entry found in database vid=4
    [ 2.920000] entry found in database vid=4
    [ 2.930000] Failed.
    [ 2.930000] gvtuFindVidEntry returned failed--> 13 .
    [ 2.940000] no entry found in database vid=5
    [ 2.940000] entry found in database vid=5
    [ 2.950000] Failed.
    [ 2.950000] gvtuFindVidEntry returned failed--> 13 .
    [ 2.960000] no entry found in database vid=6
    [ 2.960000] entry found in database vid=6
    [ 2.970000] Failed.
    [ 2.970000] gvtuFindVidEntry returned failed--> 13 .
    [ 2.970000] no entry found in database vid=7
    [ 2.980000] entry found in database vid=7
    [ 2.990000] DBNum VID Tag0 Tag1 Tag2 Tag3 Tag4 Tag5 Tag6 Tag7 Tag8 Tag9 Tag10
    [ 2.990000] 00 02 02 01 01 01 01 03
    [ 3.000000] 00 03 01 02 01 01 01 03
    [ 3.000000] 00 04 01 01 02 01 01 03
    [ 3.010000] 00 05 01 01 01 02 01 03
    [ 3.020000] 00 06 01 01 01 01 02 03
    [ 3.020000] 00 07 01 01 01 01 01 03
    [ 3.040000] dsa slave smi: probed
    [ 3.050000] register_netdev 0
    [ 3.050000] register_netdev 0
    [ 3.060000] register_netdev 0
    [ 3.070000] register_netdev 0
    [ 3.070000] register_netdev 0
    [ 3.080000] dsa_probe bus name: davinci_mdio.0
    [ 3.090000] UBIFS: parse sync
    [ 3.130000] UBIFS DBG (pid 355): ubifs_bg_thread: background thread "ubifs_bgt0_0" started, PID 355
    [ 3.220000] UBIFS: mounted UBI device 0, volume 0, name "hawkboard-rootfs"
    [ 3.230000] UBIFS: file system size: 120373248 bytes (117552 KiB, 114 MiB, 948 LEBs)
    [ 3.240000] UBIFS: journal size: 9023488 bytes (8812 KiB, 8 MiB, 72 LEBs)
    [ 3.240000] UBIFS: media format: w4/r0 (latest is w4/r0)
    [ 3.250000] UBIFS: default compressor: lzo
    [ 3.250000] UBIFS: reserved for root: 0 bytes (0 KiB)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: compiled on: Jul 21 2014 at 09:37:48
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: min. I/O unit size: 2048 bytes
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: LEB size: 126976 bytes (124 KiB)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: data journal heads: 1
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: UUID: 07EB64AC-F8D3-4DA8-B5D8-03533A5DA84A
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: big_lpt 0
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: log LEBs: 5 (3 - 7)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: LPT area LEBs: 2 (8 - 9)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: orphan area LEBs: 1 (10 - 10)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: main area LEBs: 948 (11 - 958)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: index LEBs: 28
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: total index bytes: 876840 (856 KiB, 0 MiB)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: key hash type: 0
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: tree fanout: 8
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: reserved GC LEB: 340
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: first main LEB: 11
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: max. znode size 240
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: max. index node size 192
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: node sizes: data 48, inode 160, dentry 56
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: node sizes: trun 56, sb 4096, master 512
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: node sizes: ref 64, cmt. start 32, orph 32
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: max. node sizes: data 4144, inode 4256 dentry 312
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: dead watermark: 2048
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: dark watermark: 6144
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: LEB overhead: 2656
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: max. dark space: 5824512 (5688 KiB, 5 MiB)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: maximum bud bytes: 8388608 (8192 KiB, 8 MiB)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: BG commit bud bytes: 6815744 (6656 KiB, 6 MiB)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: current bud bytes 59392 (58 KiB, 0 MiB)
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: max. seq. number: 2164760
    [ 3.260000] UBIFS DBG (pid 1): mount_ubifs: commit number: 991
    [ 3.260000] VFS: Mounted root (ubifs filesystem) on device 0:13.
    [ 3.270000] Freeing init memory: 260K
    [ 5.190000] udevd (385): /proc/385/oom_adj is deprecated, please use /proc/385/oom_score_adj instead.
    [ 13.640000] net eth0: no phy, defaulting to 100/full
    [ 13.690000] device eth0 entered promiscuous mode
    [ 14.040000] davinci_mdio davinci_mdio.0: resetting idled controller
    [ 14.040000] eth2: link up, 100 Mb/s Full Duplex PHY Mode, flow control disabled
    root@hawkboard:~#



    output of pjsua:

    root@hawkboard:~/voip_pjsip# ./pjsua-arm-none-linux-gnueabi --log-level=6
    00:18:31.662 os_core_unix.c !pjlib 2.4.5 for POSIX initialized
    00:18:31.688 sip_endpoint.c .Creating endpoint instance...
    00:18:31.696 pjlib .select() I/O Queue created (0x24d938)
    00:18:31.696 sip_endpoint.c .Module "mod-msg-print" registered
    00:18:31.697 sip_transport. .Transport manager created.
    00:18:31.698 pjsua_core.c .PJSUA state changed: NULL --> CREATED
    00:18:31.701 sip_endpoint.c .Module "mod-pjsua-log" registered
    00:18:31.701 sip_endpoint.c .Module "mod-tsx-layer" registered
    00:18:31.702 sip_endpoint.c .Module "mod-stateful-util" registered
    00:18:31.703 sip_endpoint.c .Module "mod-ua" registered
    00:18:31.706 sip_endpoint.c .Module "mod-100rel" registered
    00:18:31.707 sip_endpoint.c .Module "mod-pjsua" registered
    00:18:31.708 sip_endpoint.c .Module "mod-invite" registered
    00:18:31.927 pa_dev.c ..PortAudio sound library initialized, status=0
    00:18:31.928 pa_dev.c ..PortAudio host api count=2
    00:18:31.928 pa_dev.c ..Sound device count=3
    00:18:31.931 pjlib ..select() I/O Queue created (0x26627c)
    00:18:32.156 sip_endpoint.c .Module "mod-evsub" registered
    00:18:32.157 sip_endpoint.c .Module "mod-presence" registered
    00:18:32.158 sip_endpoint.c .Module "mod-mwi" registered
    00:18:32.158 sip_endpoint.c .Module "mod-refer" registered
    00:18:32.159 sip_endpoint.c .Module "mod-pjsua-pres" registered
    00:18:32.160 sip_endpoint.c .Module "mod-pjsua-im" registered
    00:18:32.160 sip_endpoint.c .Module "mod-pjsua-options" registered
    00:18:32.161 pjsua_core.c .1 SIP worker threads created
    00:18:32.161 pjsua_core.c .pjsua version 2.4.5 for Linux-2.6.36/armv5tejl/glibc-2.10 initialized
    00:18:32.162 pjsua_core.c .PJSUA state changed: CREATED --> INIT
    00:18:32.162 sip_endpoint.c Module "mod-default-handler" registered
    00:18:32.172 pjsua_core.c SIP UDP socket reachable at 192.168.2.215:5060
    00:18:32.176 udp0x275270 SIP UDP transport started, published address is 192.168.2.215:5060
    00:18:32.177 pjsua_acc.c Adding account: id=<sip:192.168.2.215:5060>
    00:18:32.178 pjsua_acc.c .Account <sip:192.168.2.215:5060> added with id 0
    00:18:32.179 pjsua_acc.c Modifying account 0
    00:18:32.180 pjsua_acc.c Acc 0: setting online status to 1..
    00:18:32.190 tcptp:5060 SIP TCP listener ready for incoming connections at 192.168.2.215:5060
    00:18:32.191 pjsua_acc.c Adding account: id=<sip:192.168.2.215:5060;transport=TCP>
    00:18:32.191 pjsua_acc.c .Account <sip:192.168.2.215:5060;transport=TCP> added with id 1
    00:18:32.192 pjsua_acc.c Modifying account 1
    00:18:32.192 pjsua_acc.c Acc 1: setting online status to 1..
    00:18:32.193 pjsua_core.c PJSUA state changed: INIT --> STARTING
    00:18:32.195 sip_endpoint.c .Module "mod-unsolicited-mwi" registered
    00:18:32.196 pjsua_core.c .PJSUA state changed: STARTING --> RUNNING
    00:18:32.196 main.c Ready: Success
    >>>>
    Account list:
    [ 0] <sip:192.168.2.215:5060>: does not register
    Online status: Online
    *[ 1] <sip:192.168.2.215: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 | |
    +-----------------------------------------------------------------------------+
    | q QUIT L ReLoad sleep MS echo [0|1|txt] n: detect NAT type |
    +=============================================================================+
    You have 0 active call
    >>> 00:18:55.131 pjsua_core.c .RX 605 bytes Request msg SUBSCRIBE/cseq=18280 (rdata0x2768a4) from UDP 192.168.2.234:5060:
    SUBSCRIBE sip:192.168.2.215:5060 SIP/2.0
    Via: SIP/2.0/UDP 192.168.2.234:5060;rport;branch=z9hG4bKPj.BBFLm-XqtgQiaC5B4WBfecTmdsQoN-h
    Max-Forwards: 70
    From: <sip:192.168.2.234>;tag=ckC60Yr6vR8f.WWyEG8cy48fZN3ENkJv
    To: sip:192.168.2.215
    Contact: <sip:192.168.2.234:5060;ob>
    Call-ID: OJBSiv49OI02hx5QYyNDqinctAm4VgWz
    CSeq: 18280 SUBSCRIBE
    Event: presence
    Expires: 600
    Supported: replaces, 100rel, timer, norefersub
    Accept: application/pidf+xml, application/xpidf+xml
    Allow-Events: presence, message-summary, refer
    User-Agent: PJSUA v2.4.5 Linux-3.19.0.43/x86_64/glibc-2.19
    Content-Length: 0


    --end msg--
    00:18:55.133 pjsua_pres.c .Creating server subscription, using account 0
    00:18:55.145 pjsua_core.c ....TX 545 bytes Response msg 200/SUBSCRIBE/cseq=18280 (tdta0x27b658) to UDP 192.168.2.234:5060:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 192.168.2.234:5060;rport=5060;received=192.168.2.234;branch=z9hG4bKPj.BBFLm-XqtgQiaC5B4WBfecTmdsQoN-h
    Call-ID: OJBSiv49OI02hx5QYyNDqinctAm4VgWz
    From: <sip:192.168.2.234>;tag=ckC60Yr6vR8f.WWyEG8cy48fZN3ENkJv
    To: <sip:192.168.2.215>;tag=wu3j4AM.InHjIUKZunbm6pLijlqMDS-1
    CSeq: 18280 SUBSCRIBE
    Expires: 600
    Contact: <sip:192.168.2.215:5060>
    Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
    Supported: replaces, 100rel, timer, norefersub
    Content-Length: 0


    --end msg--
    00:18:55.147 pjsua_pres.c ..Acc 0: sending NOTIFY for srv_pres=0x0x27af88..
    00:18:55.151 pjsua_core.c .....TX 880 bytes Request msg NOTIFY/cseq=12697 (tdta0x27e5a8) to UDP 192.168.2.234:5060:
    NOTIFY sip:192.168.2.234:5060;ob SIP/2.0
    Via: SIP/2.0/UDP 192.168.2.215:5060;rport;branch=z9hG4bKPj1amyupOxeFIvvN46b5dNch4hvFF8NlAY
    Max-Forwards: 70
    From: <sip:192.168.2.215>;tag=wu3j4AM.InHjIUKZunbm6pLijlqMDS-1
    To: <sip:192.168.2.234>;tag=ckC60Yr6vR8f.WWyEG8cy48fZN3ENkJv
    Contact: <sip:192.168.2.215:5060>
    Call-ID: OJBSiv49OI02hx5QYyNDqinctAm4VgWz
    CSeq: 12697 NOTIFY
    Event: presence
    Subscription-State: active;expires=599
    Allow-Events: presence, message-summary, refer
    User-Agent: PJSUA v2.4.5 Linux-2.6.36/armv5tejl/glibc-2.10
    Content-Type: application/pidf+xml
    Content-Length: 278

    <?xml version="1.0" encoding="UTF-8"?>
    <presence entity="sip:192.168.2.215" xmlns="urn:ietf:params:xml:ns:pidf">
    <tuple id="wAQykr1qrMJHukiz.VuYpOJtpIn4uWAJ">
    <status>
    <basic>open</basic>
    </status>
    <timestamp>2011-05-19T00:18:55.149Z</timestamp>
    </tuple>
    </presence>

    --end msg--
    00:18:55.157 evsub0x27a634 ...Subscription state changed NULL --> ACTIVE
    00:18:55.157 pjsua_pres.c ....Server subscription to sip:192.168.2.234 is ACTIVE
    00:18:55.159 pjsua_core.c .RX 531 bytes Response msg 200/NOTIFY/cseq=12697 (rdata0x2768a4) from UDP 192.168.2.234:5060:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 192.168.2.215:5060;rport=5060;received=192.168.2.215;branch=z9hG4bKPj1amyupOxeFIvvN46b5dNch4hvFF8NlAY
    Call-ID: OJBSiv49OI02hx5QYyNDqinctAm4VgWz
    From: <sip:192.168.2.215>;tag=wu3j4AM.InHjIUKZunbm6pLijlqMDS-1
    To: <sip:192.168.2.234>;tag=ckC60Yr6vR8f.WWyEG8cy48fZN3ENkJv
    CSeq: 12697 NOTIFY
    Contact: <sip:192.168.2.234:5060;ob>
    Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
    Supported: replaces, 100rel, timer, norefersub
    Content-Length: 0


    --end msg--
    00:19:01.069 pjsua_core.c .RX 1109 bytes Request msg INVITE/cseq=6188 (rdata0x2768a4) from UDP 192.168.2.234:5060:
    INVITE sip:192.168.2.215:5060 SIP/2.0
    Via: SIP/2.0/UDP 192.168.2.234:5060;rport;branch=z9hG4bKPjEnIfF2s4eF4fg3uTfsYbE9YkhH19OTdm
    Max-Forwards: 70
    From: <sip:192.168.2.234>;tag=AIR40DN6BDjNVVeEtJ8a2pfmv2KpD.64
    To: sip:192.168.2.215
    Contact: <sip:192.168.2.234:5060;ob>
    Call-ID: cBssk3ZD0Qx.tJJi4PYqkMEZgDc36CgJ
    CSeq: 6188 INVITE
    Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
    Supported: replaces, 100rel, timer, norefersub
    Session-Expires: 1800
    Min-SE: 90
    User-Agent: PJSUA v2.4.5 Linux-3.19.0.43/x86_64/glibc-2.19
    Content-Type: application/sdp
    Content-Length: 476

    v=0
    o=- 3662205670 3662205670 IN IP4 192.168.2.234
    s=pjmedia
    b=AS:84
    t=0 0
    a=X-nat:0
    m=audio 4000 RTP/AVP 98 97 99 104 3 0 8 9 96
    c=IN IP4 192.168.2.234
    b=TIAS:64000
    a=rtcp:4001 IN IP4 192.168.2.234
    a=sendrecv
    a=rtpmap:98 speex/16000
    a=rtpmap:97 speex/8000
    a=rtpmap:99 speex/32000
    a=rtpmap:104 iLBC/8000
    a=fmtp:104 mode=30
    a=rtpmap:3 GSM/8000
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:9 G722/8000
    a=rtpmap:96 telephone-event/8000
    a=fmtp:96 0-16

    --end msg--
    00:19:01.072 pjsua_call.c .Incoming Request msg INVITE/cseq=6188 (rdata0x2768a4)
    00:19:01.081 pjsua_media.c ..Call 0: initializing media..
    00:19:01.090 pjsua_media.c ...RTP socket reachable at 192.168.2.215:4000
    00:19:01.091 pjsua_media.c ...RTCP socket reachable at 192.168.2.215:4001
    00:19:01.092 pjsua_media.c ...Media index 0 selected for audio call 0
    00:19:01.101 pjsua_core.c .....TX 313 bytes Response msg 100/INVITE/cseq=6188 (tdta0x287770) to UDP 192.168.2.234:5060:
    SIP/2.0 100 Trying
    Via: SIP/2.0/UDP 192.168.2.234:5060;rport=5060;received=192.168.2.234;branch=z9hG4bKPjEnIfF2s4eF4fg3uTfsYbE9YkhH19OTdm
    Call-ID: cBssk3ZD0Qx.tJJi4PYqkMEZgDc36CgJ
    From: <sip:192.168.2.234>;tag=AIR40DN6BDjNVVeEtJ8a2pfmv2KpD.64
    To: <sip:192.168.2.215>
    CSeq: 6188 INVITE
    Content-Length: 0


    --end msg--
    ring_start
    ring_start
    00:19:01.105 pjsua_aud.c ..Conf connect: 2 --> 0
    00:19:01.106 pjsua_aud.c ...Set sound device: capture=-1, playback=-2
    00:19:01.106 pjsua_app.c ....Turning sound device ON
    00:19:01.107 pjsua_aud.c ....Opening sound device PCM@16000/1/20ms
    00:19:01.268 ec0x28ce70 .....AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=0 ms
    00:19:01.299 conference.c ...Port 2 (ring) transmitting to port 0 (DA850/OMAP-L138 EVM: (hw:0,0))
    00:19:01.299 pjsua_app.c ..Incoming call for account 0!
    Media count: 1 audio & 0 video
    From: <sip:192.168.2.234>
    To: <sip:192.168.2.215>
    Press a to answer or h to reject call
    00:19:01.381 os_core_unix.c Info: possibly re-registering existing thread
    a
    Answer with code (100-699) (empty to cancel): 200
    00:19:18.154 pjsua_call.c !Answering call 0: code=200
    00:19:18.156 pjsua_media.c ...Call 0: updating media..
    00:19:18.158 pjsua_aud.c ....Audio channel update..
    00:19:18.160 strm0x2b7da4 .....VAD temporarily disabled
    00:19:18.162 strm0x2b7da4 .....Encoder stream started
    00:19:18.162 strm0x2b7da4 .....Decoder stream started
    00:19:18.166 pjsua_media.c ....Audio updated, stream #0: speex (sendrecv)
    00:19:18.166 pjsua_app.c ...Call 0 media 0 [type=audio], status is Active
    ring_stop
    00:19:18.167 pjsua_aud.c ...Conf disconnect: 2 -x- 0
    00:19:18.167 conference.c ....Port 2 (ring) stop transmitting to port 0 (DA850/OMAP-L138 EVM: (hw:0,0))
    00:19:18.167 pjsua_aud.c ...Conf connect: 3 --> 0
    00:19:18.168 conference.c ....Port 3 (sip:192.168.2.234) transmitting to port 0 (DA850/OMAP-L138 EVM: (hw:0,0))
    00:19:18.168 pjsua_aud.c ...Conf connect: 0 --> 3
    00:19:18.168 conference.c ....Port 0 (DA850/OMAP-L138 EVM: (hw:0,0)) transmitting to port 3 (sip:192.168.2.234)
    00:19:18.170 pjsua_core.c ....TX 892 bytes Response msg 200/INVITE/cseq=6188 (tdta0x287770) to UDP 192.168.2.234:5060:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 192.168.2.234:5060;rport=5060;received=192.168.2.234;branch=z9hG4bKPjEnIfF2s4eF4fg3uTfsYbE9YkhH19OTdm
    Call-ID: cBssk3ZD0Qx.tJJi4PYqkMEZgDc36CgJ
    From: <sip:192.168.2.234>;tag=AIR40DN6BDjNVVeEtJ8a2pfmv2KpD.64
    To: <sip:192.168.2.215>;tag=ryd.kUtkq9iIrt3Y6icCEn-KkOch8Nt9
    CSeq: 6188 INVITE
    Contact: <sip:192.168.2.215:5060>
    Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
    Supported: replaces, 100rel, timer, norefersub
    Session-Expires: 1800;refresher=uac
    Require: timer
    Content-Type: application/sdp
    Content-Length: 278

    v=0
    o=- 3514753141 3514753142 IN IP4 192.168.2.215
    s=pjmedia
    b=AS:84
    t=0 0
    a=X-nat:0
    m=audio 4000 RTP/AVP 98 96
    c=IN IP4 192.168.2.215
    b=TIAS:64000
    a=rtcp:4001 IN IP4 192.168.2.215
    a=sendrecv
    a=rtpmap:98 speex/16000
    a=rtpmap:96 telephone-event/8000
    a=fmtp:96 0-16

    --end msg--
    00:19:18.172 pjsua_app.c .......Call 0 state changed to CONNECTING
    >>> 00:19:18.178 pjsua_core.c .RX 351 bytes Request msg ACK/cseq=6188 (rdata0x2768a4) from UDP 192.168.2.234:5060:
    ACK sip:192.168.2.215:5060 SIP/2.0
    Via: SIP/2.0/UDP 192.168.2.234:5060;rport;branch=z9hG4bKPjvLWSlLnwQOBFn.faNAlL9ALeyDMBQiW-
    Max-Forwards: 70
    From: <sip:192.168.2.234>;tag=AIR40DN6BDjNVVeEtJ8a2pfmv2KpD.64
    To: sip:192.168.2.215;tag=ryd.kUtkq9iIrt3Y6icCEn-KkOch8Nt9
    Call-ID: cBssk3ZD0Qx.tJJi4PYqkMEZgDc36CgJ
    CSeq: 6188 ACK
    Content-Length: 0


    --end msg--
    00:19:18.180 pjsua_app.c ...Call 0 state changed to CONFIRMED