Uploaded image for project: ' AGL Development'
  1. AGL Development
  2. SPEC-2850

[pipewire/wireplumber] sound output only on one stereo channel

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Fixed
    • Icon: Major Major
    • Icefish 9.0.0
    • master
    • None
    • None
    • qemux86-64 , master, csl usb audio
    • Intel Minnowboard, QEMU x86_64, Renesas H3

      We only can hear one (e.g. left) channel of a stereo audio playback.

       

      I confirmed this during the F2F with the fibredyne amp over inic-net and also through qemu with device passthrough:

      For the record, qemu was called like

       $ qemu-system-x86_64 -device virtio-net-pci,netdev=net0,mac=52:54:00:12:35:02 -netdev user,id=net0,hostfwd=tcp::2222-:22,hostfwd=tcp::2323-:23,tftp=/home/scratch/user/git/AGL/build-from-repo/build-qemux86-64/tmp/deploy/images/qemux86-64 -drive file=/home/scratch/user/git/AGL/build-from-repo/build-qemux86-64/tmp/deploy/images/qemux86-64/agl-demo-platform-crosssdk-qemux86-64-20191002184422.rootfs.ext4,if=virtio,format=raw -vga vmware -show-cursor -usb -device usb-tablet -device virtio-rng-pci -vga virtio -device qemu-xhci -device usb-host,hostbus=2,hostaddr=13 -usb -device usb-host,hostbus=2,hostaddr=14  -vnc :0 -machine q35 -cpu kvm64 -cpu qemu64,+ssse3,+sse4.1,+sse4.2,+popcnt -enable-kvm -m 2048 -serial mon:vc -serial null -kernel /home/scratch/user/git/AGL/build-from-repo/build-qemux86-64/tmp/deploy/images/qemux86-64/bzImage--4.18.33+git0+865683fc87_1a564c76f4-r0-qemux86-64-20190926225326.bin -append 'root=/dev/vda rw highres=off  mem=2048M ip=dhcp vga=0 uvesafb.mode_option=1280x1024-32 oprofile.timer=1 uvesafb.task_timeout=-1 console=ttyS0,115200n8 quiet '

      qemu is version 3.1.0

       

      log:

      Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524841][floatmix.c:564 impl_node_port_use_buffers()] floatmix 0x7f4cc877e0d8: use buffers 1 on port 1:0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524847][merger.c:771 impl_node_port_use_buffers()] merger 0x7f4cc9e0e748: use buffers 1 on port 0:0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524852][merger.c:542 clear_buffers()] merger 0x7f4cc9e0e748: clear buffers 0x7f4cc9e0e8d8
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524857][audioconvert.c:398 setup_buffers()] audioconvert 0x7f4cc9e0e228: 0 3
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524866][audioadapter.c:813 impl_node_port_use_buffers()] audioadapter 0x7f4cc9e0e038: 1 0:0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524872][audioadapter.c:652 negotiate_buffers()] 0x7f4cc9e0e038: 1
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524877][port.c:107 pw_port_update_state()] port 0x55de889cf9c0: state ready -> paused ((null))
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524883][floatmix.c:564 impl_node_port_use_buffers()] floatmix 0x7f4cc877e0d8: use buffers 1 on port 0:0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524889][link.c:186 complete_paused()] link 0x55de88aefba0: obj:0x55de889cf9c0 port 0x55de889cf9c0 complete PAUSED: Success
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524895][link.c:130 pw_link_update_state()] link 0x55de88aefba0: update state allocating -> paused
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524905][link.c:101 debug_link()] link 0x55de88aefba0: 2 2 0 out 1 0 1 , 1 0 1 in 0 0 0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524911][node.c:1742 pw_node_set_state()] node 0x55de889e1ab0: set state running -> running, active 1
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524921][node.c:1742 pw_node_set_state()] node 0x55de889b7190: set state suspended -> running, active 1
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524927][node.c:1710 node_activate()] node 0x55de889b7190: activate
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524936][link.c:752 pw_link_activate()] link 0x55de88aefba0: activate 0 3
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524942][link.c:902 pw_link_prepare()] link 0x55de88aefba0: prepare 1
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524947][link.c:526 port_set_io()] link 0x55de88aefba0: output port 0x55de889e37a0 0.0 set io: 1 0x55de88aeff00 8
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524953][link.c:526 port_set_io()] link 0x55de88aefba0: input port 0x55de889cf9c0 0.0 set io: 1 0x55de88aeff00 8
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.524958][floatmix.c:611 impl_node_port_set_io()] floatmix 0x7f4cc877e0d8: port 0:0 io 1 0x55de88aeff00/8
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526052][node.c:199 start_node()] node 0x55de889b7190: start node 0 0 1 1
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526064][alsa-utils.c:1151 spa_alsa_start()] alsa 0x55de8899b7f8: start 512 duration:512 rate:48000 slave:0
Oct 02 21:29:14 qemux86-64 pipewire[606]: Hardware PCM card 0 'USB Sound Device' device 0 subdevice 0
Oct 02 21:29:14 qemux86-64 pipewire[606]: Its setup is:
Oct 02 21:29:14 qemux86-64 pipewire[606]:   stream       : PLAYBACK
Oct 02 21:29:14 qemux86-64 pipewire[606]:   access       : MMAP_INTERLEAVED
Oct 02 21:29:14 qemux86-64 pipewire[606]:   format       : S16_LE
Oct 02 21:29:14 qemux86-64 pipewire[606]:   subformat    : STD
Oct 02 21:29:14 qemux86-64 pipewire[606]:   channels     : 8
Oct 02 21:29:14 qemux86-64 pipewire[606]:   rate         : 48000
Oct 02 21:29:14 qemux86-64 pipewire[606]:   exact rate   : 48000 (48000/1)
Oct 02 21:29:14 qemux86-64 pipewire[606]:   msbits       : 16
Oct 02 21:29:14 qemux86-64 pipewire[606]:   buffer_size  : 65536
Oct 02 21:29:14 qemux86-64 pipewire[606]:   period_size  : 1024
Oct 02 21:29:14 qemux86-64 pipewire[606]:   period_time  : 21333
Oct 02 21:29:14 qemux86-64 pipewire[606]:   tstamp_mode  : ENABLE
Oct 02 21:29:14 qemux86-64 pipewire[606]:   tstamp_type  : MONOTONIC
Oct 02 21:29:14 qemux86-64 pipewire[606]:   period_step  : 1
Oct 02 21:29:14 qemux86-64 pipewire[606]:   avail_min    : 1024
Oct 02 21:29:14 qemux86-64 pipewire[606]:   period_event : 0
Oct 02 21:29:14 qemux86-64 pipewire[606]:   start_threshold  : 9223372036854775807
Oct 02 21:29:14 qemux86-64 pipewire[606]:   stop_threshold   : 65536
Oct 02 21:29:14 qemux86-64 pipewire[606]:   silence_threshold: 0
Oct 02 21:29:14 qemux86-64 pipewire[606]:   silence_size : 0
Oct 02 21:29:14 qemux86-64 pipewire[606]:   boundary     : 4611686018427387904
Oct 02 21:29:14 qemux86-64 pipewire[606]:   appl_ptr     : 0
Oct 02 21:29:14 qemux86-64 pipewire[606]:   hw_ptr       : 0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526233][work-queue.c:194 pw_work_queue_add()] work-queue 0x55de889b7ae0: defer object 0x55de889b7190
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526244][link.c:752 pw_link_activate()] link 0x55de88aefba0: activate 1 3
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526250][work-queue.c:186 pw_work_queue_add()] work-queue 0x55de88aeffe0: wait sync object 0x55de88aefba0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526384][work-queue.c:84 process_work_queue()] work-queue 0x55de88aeffe0: 0 process work item 0x55de88aefba0 -1 -16
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526394][work-queue.c:84 process_work_queue()] work-queue 0x55de889b7ae0: 0 process work item 0x55de889b7190 -1 0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526400][node.c:1698 on_state_complete()] node 0x55de889b7190: state complete 0
Oct 02 21:29:14 qemux86-64 pipewire[606]: [D][000000280.526411][node.c:301 node_update_state()] node 0x55de889b7190: update state from suspended -> running
Oct 02 21:29:17 qemux86-64 pipewire[606]: [D][000000283.533217][alsa-utils.c:677 update_time()] slave:0 rate:0.993255 bw:0.064000 del:64 target:448 err:32.000000 (0.001434 0.003093 0.003652)
Oct 02 21:29:20 qemux86-64 pipewire[606]: [D][000000286.538114][alsa-utils.c:677 update_time()] slave:0 rate:0.993887 bw:0.016000 del:64 target:448 err:288.000000 (0.002006 0.001487 0.004625)
Oct 02 21:29:23 qemux86-64 pipewire[606]: [D][000000289.543082][alsa-utils.c:677 update_time()] slave:0 rate:0.994806 bw:0.016000 del:64 target:448 err:-224.000000 (0.000356 0.000409 0.004785)
Oct 02 21:29:26 qemux86-64 pipewire[606]: [D][000000292.560089][alsa-utils.c:677 update_time()] slave:0 rate:0.994808 bw:0.016000 del:64 target:448 err:288.000000 (0.000343 0.000338 0.004854)
Oct 02 21:29:29 qemux86-64 pipewire[606]: [D][000000295.570112][alsa-utils.c:677 update_time()] slave:0 rate:0.994714 bw:0.016000 del:64 target:448 err:-192.000000 (0.000435 0.000369 0.004917)
Oct 02 21:29:32 qemux86-64 pipewire[606]: [D][000000298.588115][alsa-utils.c:677 update_time()] slave:0 rate:0.994718 bw:0.016000 del:64 target:448 err:32.000000 (0.000329 0.000294 0.004988)
Oct 02 21:29:35 qemux86-64 pipewire[606]: [D][000000301.592145][alsa-utils.c:677 update_time()] slave:0 rate:0.994628 bw:0.016000 del:64 target:448 err:48.000000 (0.000341 0.000321 0.005050)

       

       

        1. qemu-pipewire-audio-log
          7 kB
        2. qemu-pipewire-passthrough-csl.txt
          61 kB
        No reviews matched the request. Check your Options in the drop-down menu of this sections header.

            gkiagia Georgios Kiagiadakis
            jsmoeller Jan-Simon Moeller
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: