On Fri, Jan 7, 2011 at 1:11 AM, patrick keshishian <pkesh...@gmail.com> wrote: > It seems after I sleep-wake cycle my laptop something screw happens so > that play back of music -- specifically using mpg123 to play mp3 files > -- after that point produces a fair amount of static. > > Here is an odd part. If I run aucat as such: > > $ aucat -ddddd > <much continuous output> > > the static is almost nonexistent, while reducing one of the -d options > makes the static more noticeable. Suggesting keeping aucat "busy" > reduces static significantly(?). > > Any ideas? What other info would be useful? > > Thanks, > --patrick > > > Here is a diff from output from 'aucat -dddd' before and after zzz: > > --- /tmp/before.zzz.txt Fri Jan 7 00:54:53 2011 > +++ /tmp/after.zzz.txt Fri Jan 7 00:56:46 2011 > @@ -15,7 +15,6 @@ sock/hel|rmsg|widl: using mode = 1 > mpg0: overwritten slot 0 > sock/hel|rmsg|widl: no read buffer to set volume yet > mpg0(127)/off: changing volume to 127 > -mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: SETPAR message > mpg0/ini|rmsg|widl: using 16bits, 2 bytes per sample > mpg0: buffer size = 11648, play = s16le,0:1,44100 > @@ -42,7 +41,7 @@ rsock(sock): done > wsock(sock): terminating... > wsock(sock): done > wsock(sock): freed > -sock(sock|rRw): terminating... > +sock(sock|rRwW): terminating... > rsock(sock): freed > sock(sock|rwZ): terminating... > sock(sock|rwZ): destroyed > > full after: > $ aucat -dddd > sio(default|): created > default: recording s16le,0:1,44100 > default: playing s16le,0:1,44100 > mix(play): newout, will use 11648 fr > default: block size is 2912 frames, using 4 blocks > defa...@default: rec=0:1 play=0:1 vol=32768 > listen(/tmp/aucat-1000/softaudio0|): created > sock(sock|): created > sock/hel|rmsg|widl: HELLO message > sock/hel|rmsg|widl: hello from <mpg123>, mode = 1, ver 3 > default: option found > device requested > sock/hel|rmsg|widl: using mode = 1 > mpg0: overwritten slot 0 > sock/hel|rmsg|widl: no read buffer to set volume yet > mpg0(127)/off: changing volume to 127 > mpg0/ini|rmsg|widl: SETPAR message > mpg0/ini|rmsg|widl: using 16bits, 2 bytes per sample > mpg0: buffer size = 11648, play = s16le,0:1,44100 > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: START message > mpg0/sta|rmsg|widl: allocating 11648/23296 fr buffers, rmax = 93184 > mpg0/sta|rmsg|widl: STOP message > mpg0/run|rmsg|widl: attaching at 0 > rsock(sock)->mix(play): setmaster: 32768/32768 > syncing device, mix(play): todo = 0: lat = 0, sub(rec): lat = 0 > rsock(sock)->mix(play): setting volume to 32768 > mpg0/ini|rmsg|widl: freeing buffers > rsock(sock)->mix(play): eof requested > mix(play): running other streams > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: BYE message > rsock(sock): terminating... > rsock(sock): done > wsock(sock): terminating... > wsock(sock): done > wsock(sock): freed > sock(sock|rRwW): terminating... > rsock(sock): freed > sock(sock|rwZ): terminating... > sock(sock|rwZ): destroyed > device released > starting device > sio(default|): started > sock(sock|): created > sock/hel|rmsg|widl: HELLO message > sock/hel|rmsg|widl: hello from <mpg123>, mode = 1, ver 3 > default: option found > device requested > sock/hel|rmsg|widl: using mode = 1 > mpg0: found slot 0 > sock/hel|rmsg|widl: no read buffer to set volume yet > mpg0(127)/off: changing volume to 127 > mpg0/ini|rmsg|widl: SETPAR message > mpg0/ini|rmsg|widl: using 16bits, 2 bytes per sample > mpg0/ini|rmsg|widl: using playback channels 0..1 > mpg0/ini|rmsg|widl: using 44100Hz sample rate, 2912 fr block size > mpg0/ini|rmsg|widl: using 11648 buffer size > mpg0: buffer size = 11648, play = s16le,0:1,44100 > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: START message > mpg0/sta|rmsg|widl: allocating 11648/23296 fr buffers, rmax = 93184 > mpg0/sta|rmsg|widl: STOP message > mpg0/run|rmsg|widl: attaching at -11648 > rsock(sock)->mix(play): setmaster: 32768/32768 > syncing device, mix(play): todo = 0: lat = 11648, sub(rec): lat = 0 > rsock(sock)->mix(play): setting volume to 32768 > mpg0/ini|rmsg|widl: freeing buffers > rsock(sock)->mix(play): eof requested > mix(play): running other streams > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: BYE message > rsock(sock): terminating... > rsock(sock): done > wsock(sock): terminating... > wsock(sock): done > wsock(sock): freed > sock(sock|rRw): terminating... > rsock(sock): freed > sock(sock|rwZ): terminating... > sock(sock|rwZ): destroyed > device released > sock(sock|): created > sock/hel|rmsg|widl: HELLO message > sock/hel|rmsg|widl: hello from <mpg123>, mode = 1, ver 3 > default: option found > device requested > sock/hel|rmsg|widl: using mode = 1 > mpg0: found slot 0 > sock/hel|rmsg|widl: no read buffer to set volume yet > mpg0(127)/off: changing volume to 127 > mpg0/ini|rmsg|widl: SETPAR message > mpg0/ini|rmsg|widl: using 16bits, 2 bytes per sample > mpg0/ini|rmsg|widl: using playback channels 0..1 > mpg0/ini|rmsg|widl: using 44100Hz sample rate, 2912 fr block size > mpg0/ini|rmsg|widl: using 11648 buffer size > mpg0: buffer size = 11648, play = s16le,0:1,44100 > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: GETPAR message > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: START message > mpg0/sta|rmsg|widl: allocating 11648/23296 fr buffers, rmax = 93184 > mpg0/run|rmsg|widl: attaching at -11648 > rsock(sock)->mix(play): setmaster: 32768/32768 > syncing device, mix(play): todo = 0: lat = 11648, sub(rec): lat = 0 > rsock(sock)->mix(play): setting volume to 32768 > mpg0/run|rmsg|widl: STOP message > mpg0/ini|rmsg|widl: freeing buffers > rsock(sock)->mix(play): eof requested > mix(play): running other streams > mpg0/ini|rret|widl: RRET done > mpg0/ini|rmsg|widl: BYE message > rsock(sock): terminating... > rsock(sock): done > wsock(sock): terminating... > wsock(sock): done > wsock(sock): freed > sock(sock|rRw): terminating... > rsock(sock): freed > sock(sock|rwZ): terminating... > sock(sock|rwZ): destroyed > device released > device idle, suspending > device stopped > sio(default|): stopped > mix(play)->wsio(default): cleared > rsio(default)->sub(rec): cleared > ^Clisten(/tmp/aucat-1000/softaudio0|): closing > listen(/tmp/aucat-1000/softaudio0|Z): terminating... > listen(/tmp/aucat-1000/softaudio0|Z): destroyed > flushing device > mix(play)->wsio(default): cleared > rsio(default)->sub(rec): cleared > closing device > mix(play): terminating... > mix(play)->wsio(default): eof requested > wsio(default): terminating... > wsio(default): done > rsio(default): terminating... > rsio(default): done > rsio(default)->sub(rec): eof requested > sub(rec): terminating... > sub(rec): free delayed > sio(default|): terminating... > sio(default|): destroyed > rsio(default): free delayed > wsio(default): free delayed > mix(play): free delayed > ctl(ctl): terminating... > ctl(ctl): done > ctl(ctl): free delayed > zomb(play): freed > zomb(default): freed > zomb(rec): freed > zomb(default): freed > zomb(ctl): freed > nothing to do... > > > > > Running GENERIC with this PCI power management diff: > > http://marc.info/?l=openbsd-tech&m=129366436512348&w=2 > > also a few printf()s leftover in sys/arch/amd64/amd64/acpi_macdep.c. > > I tried to updated sources and compile and test with a true -current > w/o any patches or mods, but that kernel panics in pci_make_tag() > leaving me in ddb> with no keyboard (machine seems to be completely > hung).
FYI, with latest check-in from kettenis@, this panic is gone: http://www.openbsd.org/cgi-bin/cvsweb/src/sys/arch/amd64/pci/pci_machdep.c#re v1.39 new dmesg: OpenBSD 4.8-current (GENERIC) #6: Sun Jan 9 11:48:45 PST 2011 sids...@noir.boxsoft.com:/usr/build/src/sys/arch/amd64/compile/GENERIC real mem = 1876754432 (1789MB) avail mem = 1812811776 (1728MB) mainbus0 at root bios0 at mainbus0: SMBIOS rev. 2.4 @ 0xf10d0 (17 entries) bios0: vendor Phoenix Technologies LTD version "v1.3201" date 06/18/2009 bios0: Gateway LT31 acpi0 at bios0: rev 2 acpi0: sleep states S0 S3 S4 S5 acpi0: tables DSDT FACP APIC MCFG HPET BOOT SLIC acpi0: wakeup devices PB5_(S5) OHC1(S3) OHC2(S3) EHCI(S3) HDAU(S3) acpitimer0 at acpi0: 3579545 Hz, 32 bits acpimadt0 at acpi0 addr 0xfee00000: PC-AT compat cpu0 at mainbus0: apid 0 (boot processor) cpu0: AMD Athlon(tm) Processor L110, 1197.22 MHz cpu0: FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CFLUS H,MMX,FXSR,SSE,SSE2,SSE3,CX16,NXE,MMXX,FFXSR,LONG,3DNOW2,3DNOW cpu0: 64KB 64b/line 2-way I-cache, 64KB 64b/line 2-way D-cache, 512KB 64b/line 16-way L2 cache cpu0: ITLB 32 4KB entries fully associative, 8 4MB entries fully associative cpu0: DTLB 32 4KB entries fully associative, 8 4MB entries fully associative cpu0: apic clock running at 199MHz ioapic0 at mainbus0: apid 1 pa 0xfec00000, version 21, 24 pins acpimcfg0 at acpi0 addr 0xe0000000, bus 0-8 acpihpet0 at acpi0: 14318180 Hz acpiprt0 at acpi0: bus 0 (PCI0) acpiprt1 at acpi0: bus -1 (PB3_) acpiprt2 at acpi0: bus -1 (PB4_) acpiprt3 at acpi0: bus 3 (PB5_) acpiprt4 at acpi0: bus 4 (PB6_) acpiprt5 at acpi0: bus -1 (PB7_) acpiprt6 at acpi0: bus 9 (P2P_) acpiprt7 at acpi0: bus 1 (AGP_) acpiec0 at acpi0 acpicpu0 at acpi0: C3, C2 acpitz0 at acpi0: critical temperature 100 degC acpiac0 at acpi0: AC unit online acpibat0 at acpi0: BAT1 model "UM09B44" serial 210 type LION oem "SONY" acpibtn0 at acpi0: LID_ acpibtn1 at acpi0: SLPB acpibtn2 at acpi0: PWRB acpivideo0 at acpi0: VGA_ acpivout0 at acpivideo0: LCD_ acpivout1 at acpivideo0: CRT1 acpivout2 at acpivideo0: TV__ acpivout3 at acpivideo0: DFP1 pci0 at mainbus0 bus 0 pchb0 at pci0 dev 0 function 0 "ATI RS690 Host" rev 0x00 ppb0 at pci0 dev 1 function 0 "ATI RS690 PCIE" rev 0x00 pci1 at ppb0 bus 1 vga1 at pci1 dev 5 function 0 "ATI Radeon X1250 IGP" rev 0x00 wsdisplay0 at vga1 mux 1: console (80x25, vt100 emulation) wsdisplay0: screen 1-5 added (80x25, vt100 emulation) ppb1 at pci0 dev 5 function 0 "ATI RS690 PCIE" rev 0x00 pci2 at ppb1 bus 3 re0 at pci2 dev 0 function 0 "Realtek 8101E" rev 0x02: RTL8102EL (0x2480), apic 1 int 17 (irq 5), address 00:23:8b:ef:3a:a7 rlphy0 at re0 phy 7: RTL8201L 10/100 PHY, rev. 1 ppb2 at pci0 dev 6 function 0 "ATI RS690 PCIE" rev 0x00 pci3 at ppb2 bus 4 athn0 at pci3 dev 0 function 0 "Atheros AR9285" rev 0x01: apic 1 int 18 (irq 11) athn0: AR9285 rev 2 (1T1R), ROM rev 13, address 00:26:5e:0f:bc:3a ahci0 at pci0 dev 18 function 0 "ATI SB600 SATA" rev 0x00: apic 1 int 22 (irq 11), AHCI 1.1 scsibus0 at ahci0: 32 targets sd0 at scsibus0 targ 0 lun 0: <ATA, TOSHIBA MK2555GS, FG00> SCSI3 0/direct fixed sd0: 238475MB, 512 bytes/sec, 488397168 sec total ohci0 at pci0 dev 19 function 0 "ATI SB600 USB" rev 0x00: apic 1 int 16 (irq 10), version 1.0, legacy support ohci1 at pci0 dev 19 function 1 "ATI SB600 USB" rev 0x00: apic 1 int 17 (irq 5), version 1.0, legacy support ohci2 at pci0 dev 19 function 3 "ATI SB600 USB" rev 0x00: apic 1 int 17 (irq 5), version 1.0, legacy support ohci3 at pci0 dev 19 function 4 "ATI SB600 USB" rev 0x00: apic 1 int 18 (irq 11), version 1.0, legacy support ehci0 at pci0 dev 19 function 5 "ATI SB600 USB2" rev 0x00: apic 1 int 19 (irq 11) usb0 at ehci0: USB revision 2.0 uhub0 at usb0 "ATI EHCI root hub" rev 2.00/1.00 addr 1 piixpm0 at pci0 dev 20 function 0 "ATI SBx00 SMBus" rev 0x14: SMI iic0 at piixpm0 spdmem0 at iic0 addr 0x50: 2GB DDR2 SDRAM non-parity PC2-5300CL5 SO-DIMM pciide0 at pci0 dev 20 function 1 "ATI SB600 IDE" rev 0x00: DMA, channel 0 configured to compatibility, channel 1 configured to compatibility azalia0 at pci0 dev 20 function 2 "ATI SBx00 HD Audio" rev 0x00: apic 1 int 16 (irq 10) azalia0: codecs: Realtek ALC272 audio0 at azalia0 pcib0 at pci0 dev 20 function 3 "ATI SB600 ISA" rev 0x00 ppb3 at pci0 dev 20 function 4 "ATI SB600 PCI" rev 0x00 pci4 at ppb3 bus 9 usb1 at ohci0: USB revision 1.0 uhub1 at usb1 "ATI OHCI root hub" rev 1.00/1.00 addr 1 usb2 at ohci1: USB revision 1.0 uhub2 at usb2 "ATI OHCI root hub" rev 1.00/1.00 addr 1 usb3 at ohci2: USB revision 1.0 uhub3 at usb3 "ATI OHCI root hub" rev 1.00/1.00 addr 1 usb4 at ohci3: USB revision 1.0 uhub4 at usb4 "ATI OHCI root hub" rev 1.00/1.00 addr 1 isa0 at pcib0 isadma0 at isa0 pckbc0 at isa0 port 0x60/5 pckbd0 at pckbc0 (kbd slot) pckbc0: using irq 1 for kbd slot wskbd0 at pckbd0: console keyboard, using wsdisplay0 pms0 at pckbc0 (aux slot) pckbc0: using irq 12 for aux slot wsmouse0 at pms0 mux 0 pcppi0 at isa0 port 0x61 spkr0 at pcppi0 mtrr: Pentium Pro MTRR support uvideo0 at uhub0 port 4 configuration 1 interface 0 "Chicony Electronics Co., Ltd. CNF9011" rev 2.00/13.57 addr 2 video0 at uvideo0 uhidev0 at uhub2 port 1 configuration 1 interface 0 "Microsoft Corporation Microsoft \M-. Laser Mouse 6000" rev 2.00/1.01 addr 2 uhidev0: iclass 3/1 ums0 at uhidev0: 5 buttons, Z dir wsmouse1 at ums0 mux 0 vscsi0 at root scsibus1 at vscsi0: 256 targets softraid0 at root root on sd0a swap on sd0b dump on sd0b