Opened 10 years ago

Last modified 2 years ago

#4285 new bug

emuxki goes dead after media server restart

Reported by: anevilyak Owned by: korli
Priority: normal Milestone: R1
Component: Drivers/Audio/emuxki Version: R1/Development
Keywords: Cc:
Blocked By: Blocking:
Has a Patch: no Platform: All

Description

If I restart the media_server, I can no longer get audio until a reboot. MediaPlayer for instance simply sits stuck at the first frame of any file I try to throw at it.

device Input device controller [9|80|0]
  vendor 1102: Creative Labs
  device 7002: SB Live! Game Port

device Multimedia controller (Multimedia audio controller) [4|1|0]
  vendor 1102: Creative Labs
  device 0002: SB Live! EMU10k1

Let me know if any other info from this system would be helpful. I also notice after a day or so, audio starts getting scratchy/skippy, even under no system load. Works correctly on fresh boot/reboot.

Attachments (1)

minicom.cap (100.8 KB ) - added by anevilyak 9 years ago.

Download all attachments as: .zip

Change History (10)

comment:1 by anevilyak, 10 years ago

log from media_server after such a restart:

BMessage(0x0) {
        be:_default = int32(0xfffffffe or -2)
        be:_addon_id = int32(0x5 or 5)
        be:_internal_id = int32(0x0 or 0)
        be:_input_id = int32(0x0 or 0)
        be:_flavor_name = string("Emuxki SB Live 5.1", 19 bytes)
        be:_path = string("/boot/system/add-ons/media/hmulti_audio.media_addon", 52 bytes)
}
BMessage(0x0) {
        be:_default = int32(0xffffffff or -1)
        be:_addon_id = int32(0x5 or 5)
        be:_internal_id = int32(0x0 or 0)
        be:_input_id = int32(0x0 or 0)
        be:_flavor_name = string("Emuxki SB Live 5.1", 19 bytes)
        be:_path = string("/boot/system/add-ons/media/hmulti_audio.media_addon", 52 bytes)
}
couldn't find MediaAddonServerPort
NodeManager::RegisterAddon: ref-name "cortex_audioadapter.media_addon", assigning id 1
NodeManager::AddDormantFlavorInfo, addon-id 1, flavor-id 0, name "AudioAdapter", flavor-name "AudioAdapter", flavor-info "AudioAdapter (generic raw-audio format conversion).
by Eric Moon (8 September 1999)"
NodeManager::RegisterAddon: ref-name "cortex_flanger.media_addon", assigning id 2
NodeManager::AddDormantFlavorInfo, addon-id 2, flavor-id 0, name "Flanger", flavor-name "Flanger", flavor-info "An add-on version of FlangerNode.
by Eric Moon (16 June, 1999)"
NodeManager::RegisterAddon: ref-name "dvb.media_addon", assigning id 3
DVB: DVBMediaAddon::WantsAutoStart - NO
NodeManager::RegisterAddon: ref-name "firewire_dv.media_addon", assigning id 4
NodeManager::RegisterAddon: ref-name "hmulti_audio.media_addon", assigning id 5
fSettings       BMessage(0x0) {
}
NodeManager::AddDormantFlavorInfo, addon-id 5, flavor-id 0, name "Emuxki SB Live 5.1", flavor-name "Emuxki SB Live 5.1", flavor-info "The MultiAudioNode node outputs to multi_audio drivers."
NodeManager::RegisterAddon: ref-name "mixer.media_addon", assigning id 6
NodeManager::AddDormantFlavorInfo, addon-id 6, flavor-id 0, name "Audio Mixer (Haiku)", flavor-name "Audio Mixer (Haiku)", flavor-info "Haiku Audio Mixer media addon"
NodeManager::RegisterAddon: ref-name "opensound.media_addon", assigning id 7
NodeManager::RegisterAddon: ref-name "tone_producer_demo.media_addon", assigning id 8
NodeManager::AddDormantFlavorInfo, addon-id 8, flavor-id 0, name "Demo Audio Producer", flavor-name "Demo Audio Producer", flavor-info "An add-on version of the ToneProducer node.
See the Be Developer Newsletter: 2 June, 1999
adapted by Eric Moon (4 June, 1999)"
NodeManager::RegisterAddon: ref-name "usb_webcam.media_addon", assigning id 9
NodeManager::RegisterAddon: ref-name "video_producer_demo.media_addon", assigning id 10
NodeManager::AddDormantFlavorInfo, addon-id 10, flavor-id 0, name "Demo Video Producer", flavor-name "Demo Video Producer", flavor-info "Demo Video Producer"
NodeManager::RegisterAddon: ref-name "video_window_demo.media_addon", assigning id 11
NodeManager::AddDormantFlavorInfo, addon-id 11, flavor-id 0, name "VideoWindow Consumer", flavor-name "VideoWindow Consumer", flavor-info "This node displays a simple video window"
MediaAddonServer::InstantiatePhysialInputsAndOutputs: "Emuxki SB Live 5.1" is a physical input/output
BMediaRoster::InstantiateDormantNode: addon-id 5, flavor_id 0, flags 0x0
### BMediaRosterEx::LoadNodeConfiguration should load addon-id 5, flavor-id 0 config NOW!
BMediaEventLooper: SchedulingLatency is 0
Node created!
trying autostart of node 6, index 0
started node 0
BMediaEventLooper: SchedulingLatency is 0
BMediaEventLooper: SchedulingLatency is 0
DefaultManager::RescanThread() enter
QueryPort: write_port failed, msgcode 0x101, port -222, error 0x80001200 (Bad port ID)
Couldn't find physical video output node
Couldn't find physical audio output node
Couldn't find physical video input node
Couldn't find physical audio input node
Default audio mixer node created
Default DAC timesource created!
DefaultManager: Trying connect in native format (1)
DefaultManager: GetFormatFor failed
DefaultManager: Trying connect in format 1
BMediaRoster::Connect connection established!
TimeSourceObject::DeleteHook enter
BMediaRoster::ReleaseNode, trying to release reference counting disabled timesource, node 1, port 680039, team 269
TimeSourceObject::DeleteHook leave
BTimeSource::DirectAddMe should not add itself to slave nodes
TimeSourceObject::DeleteHook enter
BMediaRoster::ReleaseNode, trying to release reference counting disabled timesource, node 2, port 688246, team 262
TimeSourceObject::DeleteHook leave
BMediaRoster::ReleaseNode, trying to release reference counting disabled timesource, node 2, port 688246, team 262
DefaultManager::RescanThread() leave
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/aiff_reader"
AddOnManager::_RegisterReader, name aiff_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/ape_reader"
AddOnManager::_RegisterReader, name ape_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/asf_reader"
AddOnManager::_RegisterReader, name asf_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/au_reader"
AddOnManager::_RegisterReader, name au_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/avi_reader"
AddOnManager::_RegisterReader, name avi_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/ffmpeg"
AddOnManager::_RegisterReader, name ffmpeg
AddOnManager::_RegisterDecoder, name ffmpeg
AddOnManager::_RegisterWriter, name ffmpeg
AddOnManager::_RegisterEncoder, name ffmpeg
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/matroska"
AddOnManager::_RegisterReader, name matroska
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/mov_reader"
AddOnManager::_RegisterReader, name mov_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/mp3_decoder"
AddOnManager::_RegisterDecoder, name mp3_decoder
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/mp3_reader"
AddOnManager::_RegisterReader, name mp3_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/mp4_reader"
AddOnManager::_RegisterReader, name mp4_reader
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/musepack"
AddOnManager::_RegisterReader, name musepack
AddOnManager::_RegisterDecoder, name musepack
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/ogg"
AddOnManager::_RegisterReader, name ogg
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/raw_decoder"
AddOnManager::_RegisterDecoder, name raw_decoder
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/speex"
AddOnManager::_RegisterDecoder, name speex
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/vorbis"
AddOnManager::_RegisterDecoder, name vorbis
AddOnManager::_RegisterAddOn(): trying to load "/boot/system/add-ons/media/plugins/wav_reader"
AddOnManager::_RegisterReader, name wav_reader
FormatManager::GetFormats(): put 160 formats into message
AddOnManager::GetDecoderForFormat: searching decoder for encoding 1140
AddOnManager::GetDecoderForFormat: found decoder mp3_decoder for encoding 1140
AudioMixer::GetLatencyFor 21332, timesource is 2

comment:2 by anevilyak, 10 years ago

Serial log starting from quitting media_server to restarting server, starting media player, and attempting to play an mp3 (which appeared to play as if at ~30x speed, though no audio was actually produced):

emuxki: close()
emuxki: free()
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer written (old 256, new 0)
emuxki: emuxki_resched_timer : timer disabled
emuxki: emuxki_resched_timer state : 0
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_stream_delete
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 0
emuxki: emuxki_mem_free 1
emuxki: emuxki_mem_free 2
Last message repeated 6 times.
emuxki: emuxki_mem_free 3
emuxki: mem_size : 4096
emuxki: freeing mem
emuxki: emuxki_stream_delete
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 0
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 0
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 0
emuxki: emuxki_voice_halt
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 0
emuxki: emuxki_mem_free 1
emuxki: emuxki_mem_free 2
Last message repeated 2 times.
emuxki: emuxki_mem_free 3
emuxki: mem_size : 2048
emuxki: freeing mem
emuxki: emuxki_mem_free 1
emuxki: emuxki_mem_free 2
Last message repeated 2 times.
emuxki: emuxki_mem_free 3
emuxki: mem_size : 2048
emuxki: freeing mem
emuxki: emuxki_mem_free 1
emuxki: emuxki_mem_free 2
Last message repeated 2 times.
emuxki: emuxki_mem_free 3
emuxki: mem_size : 2048
emuxki: freeing mem
emuxki: emuxki_mem_free 1
emuxki: emuxki_mem_free 2
Last message repeated 2 times.
emuxki: emuxki_mem_free 3
emuxki: mem_size : 2048
emuxki: freeing mem
emuxki: emuxki_stream_delete
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_mem_free 1
emuxki: emuxki_mem_free 2
emuxki: emuxki_mem_free 2
emuxki: emuxki_mem_free 3
emuxki: freeing mem
emuxki: emuxki_stream_delete
emuxki: emuxki_stream_halt
emuxki: emuxki_voice_halt
emuxki: emuxki_mem_free 1
emuxki: emuxki_mem_free 2
emuxki: emuxki_mem_free 3
emuxki: freeing mem
emuxki: open()
emuxki: open() got card
emuxki: voice_new
emuxki: emuxki_stream_new
Last message repeated 3 times.
emuxki: voice_setaudio
emuxki: emuxki_stream_set_audioparms
emuxki: emuxki_voice_new
emuxki: emuxki_pmem_alloc : numblocks : 1
emuxki: emuxki_pmem_alloc : 0
emuxki: emuxki_pmem_alloc : j == numblocks 1
emuxki: allocating 4096 bytes for emuxki buffer
emuxki: area = 3443, size = 4096, log = 0X91331000, phy = 0XFC3E000
emuxki: emuxki_pmem_alloc : j == numblocks emuxki_mem_new ok
emuxki: emuxki_pmem_alloc : j == numblocks returning
emuxki: emuxki_stream_set_audioparms
emuxki: emuxki_voice_new
emuxki: emuxki_pmem_alloc : numblocks : 1
emuxki: emuxki_pmem_alloc : 0
emuxki: emuxki_pmem_alloc : 1
emuxki: emuxki_pmem_alloc : j == numblocks 1
emuxki: allocating 2048 bytes for emuxki buffer
emuxki: area = 3444, size = 4096, log = 0X91332000, phy = 0XFC3F000
emuxki: emuxki_pmem_alloc : j == numblocks emuxki_mem_new ok
emuxki: emuxki_pmem_alloc : j == numblocks returning
emuxki: emuxki_voice_new
emuxki: emuxki_pmem_alloc : numblocks : 1
emuxki: emuxki_pmem_alloc : 0
emuxki: emuxki_pmem_alloc : 1
emuxki: emuxki_pmem_alloc : 2
emuxki: emuxki_pmem_alloc : j == numblocks 1
emuxki: allocating 2048 bytes for emuxki buffer
emuxki: area = 3445, size = 4096, log = 0X91333000, phy = 0XFC4A000
emuxki: emuxki_pmem_alloc : j == numblocks emuxki_mem_new ok
emuxki: emuxki_pmem_alloc : j == numblocks returning
emuxki: emuxki_voice_new
emuxki: emuxki_pmem_alloc : numblocks : 1
emuxki: emuxki_pmem_alloc : 0
emuxki: emuxki_pmem_alloc : 1
emuxki: emuxki_pmem_alloc : 2
emuxki: emuxki_pmem_alloc : 3
emuxki: emuxki_pmem_alloc : j == numblocks 1
emuxki: allocating 2048 bytes for emuxki buffer
emuxki: area = 3446, size = 4096, log = 0X91338000, phy = 0XFC4B000
emuxki: emuxki_pmem_alloc : j == numblocks emuxki_mem_new ok
emuxki: emuxki_pmem_alloc : j == numblocks returning
emuxki: emuxki_voice_new
emuxki: emuxki_pmem_alloc : numblocks : 1
emuxki: emuxki_pmem_alloc : 0
emuxki: emuxki_pmem_alloc : 1
emuxki: emuxki_pmem_alloc : 2
emuxki: emuxki_pmem_alloc : 3
emuxki: emuxki_pmem_alloc : 4
emuxki: emuxki_pmem_alloc : j == numblocks 1
emuxki: allocating 2048 bytes for emuxki buffer
emuxki: area = 3447, size = 4096, log = 0X91339000, phy = 0XFC4C000
emuxki: emuxki_pmem_alloc : j == numblocks emuxki_mem_new ok
emuxki: emuxki_pmem_alloc : j == numblocks returning
emuxki: emuxki_stream_set_audioparms
emuxki: emuxki_voice_new
emuxki: allocating 4096 bytes for emuxki buffer
emuxki: area = 3448, size = 4096, log = 0X9133A000, phy = 0XFC4D000
emuxki: emuxki_stream_set_audioparms
emuxki: emuxki_voice_new
emuxki: allocating 4096 bytes for emuxki buffer
emuxki: area = 3449, size = 4096, log = 0X9133B000, phy = 0XFC55000
emuxki: emuxki_stream_set_recparms
emuxki: emuxki_stream_set_recparms
emuxki: emuxki_stream_commit_parms
Last message repeated 2 times.
emuxki: emuxki_voice_commit_parms idx_reg : 100
emuxki: emuxki_stream_commit_parms
emuxki: emuxki_voice_commit_parms idx_reg : 101
emuxki: B_MULTI_GET_DESCRIPTION
emuxki: request_channel_count = 32
emuxki: copying data
emuxki: B_MULTI_GET_ENABLED_CHANNELS
emuxki: B_MULTI_SET_ENABLED_CHANNELS
emuxki: set_enabled_channels 0 : enabled
emuxki: set_enabled_channels 1 : enabled
emuxki: set_enabled_channels 2 : enabled
emuxki: set_enabled_channels 3 : enabled
emuxki: B_MULTI_SET_GLOBAL_FORMAT
emuxki: B_MULTI_GET_GLOBAL_FORMAT
emuxki: B_MULTI_GET_BUFFERS
emuxki: flags = 0x0
emuxki: request_playback_buffers = 0x20
emuxki: request_playback_channels = 0x6
emuxki: request_playback_buffer_size = 0x0
emuxki: request_record_buffers = 0x20
emuxki: request_record_channels = 0x4
emuxki: request_record_buffer_size = 0x0
emuxki: emuxki_stream_get_nth_buffer
Last message repeated 19 times.
emuxki: B_MULTI_LIST_MIX_CONTROLS
emuxki: name : Record
emuxki: name : Master
emuxki: name : PCM Out
emuxki: name : CD
emuxki: name : Aux In
emuxki: name : TAD
emuxki: name : Mic
emuxki: name : Line In
emuxki: name : Center/Lfe
emuxki: multi->control_count 97
emuxki: emuxki_stream_start
emuxki: emuxki_voice_start
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer : timer enabled
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_stream_start
emuxki: emuxki_voice_start
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_voice_start
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_voice_start
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_voice_start
emuxki: emuxki_resched_timer begin
emuxki: emuxki_resched_timer state : 1
emuxki: emuxki_stream_start
emuxki: emuxki_voice_start
emuxki: emuxki_voice_start adccr_value : 0
emuxki: emuxki_voice_start adccr_value : 24, 24
emuxki: emuxki_stream_start
emuxki: emuxki_voice_start
register_domain(9, unix)
emuxki: Got unhandled interrupt
heap_add_area: area 3974 added to large heap 0x81e62ccc - usable range 0x91801000 - 0x91c00000
runtime_loader: Cannot open file /boot/apps/BeZillaBrowser/libnssckbi.so: No such file or directory
runtime_loader: Cannot open file /boot/home/config/settings/Mozilla/Firefox/Profiles/sy83m4h8.default/libnssckbi.so: No such file or directory
runtime_loader: Cannot open file /boot/apps/BeZillaBrowser/libnssckbi.so: No such file or directory
runtime_loader: Cannot open file /boot/apps/BeZillaBrowser/libnssckbi.so: No such file or directory
emuxki: Got unhandled interrupt
emuxki: Got unhandled interrupt

comment:3 by korli, 9 years ago

Could you check this again ? I don't have the card at hand anyway.

comment:4 by anevilyak, 9 years ago

Version: R1/pre-alpha1R1/Development

Still present. Attaching a serial log of system boot + media server restart with emuxki compiled with debug tracing.

by anevilyak, 9 years ago

Attachment: minicom.cap added

comment:5 by diver, 8 years ago

Component: Drivers/AudioDrivers/Audio/emuxki

comment:6 by ttcoder, 8 years ago

Cc: degea@… added

Gotta watch this one as it's affecting dsuden more of late.

comment:7 by pulkomandy, 7 years ago

My computer seems affected as well (Thinkpad X200). I'll bring it at BeGeistert in september if anyone wants to have a look.

comment:8 by ttcoder, 5 years ago

Cc: degea@… removed

comment:9 by bbjimmy, 2 years ago

This is an issue with my system as well on hrev51505

device Multimedia controller (Multimedia audio controller) [4|1|0]

vendor 8086: Intel Corporation device 24c5: 82801DB/DBL/DBM (ICH4/ICH4-L/ICH4-M) AC'97 Audio Controller

Auich ICH

device Display controller (VGA compatible controller, VGA controller) [3|0|0]

vendor 8086: Intel Corporation device 2562: 82845G/GL[Brookdale-G]/GE Chipset Integrated Graphics Device

Intel Extreme Graphics 2 (i845G)

Note: See TracTickets for help on using tickets.