Login at Kodi Home

b1nuzz · (This post was last modified: 2015-12-12, 13:54 by b1nuzz.)

Hi All,
Had a really good search but can't find my problem.

I have had an old Yamaha AVR for a few years now and all has been well. I have upgraded to a new Denon AVR which is great, other than files that have AC3, DTS etc in them from KODI now create a really horrible and loud clicking noise.

KODI is currently installed on a ATV2 however the receiver understands Netflix etc OK from it, so its not the device.
The AVR also understands the streams from other sources OK, so its not the AVR.

In KODI, with passthrough turned off, i just get 2ch through to the AVR.
With DTS Capable Receiver On, I just get 2ch through to the AVR.
With AC3 Capable Receiver turned on, I get the horribly loud clicking noise and no other audio.

Anyone have any ideas? I'm not really sure what else to change?
DEBUG below. It doesn't really mean anything to me...

Thanks

b1nuzz · 2015-12-12, 13:53

11:46:14 T:215351296 WARNING: CDVDMessageQueue(player): Tongue

ut MSGQ_NOT_INITIALIZED
11:46:14 T:215351296 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
11:46:14 T:215351296 DEBUG: LinuxRendererGL: Cleaning up GL resources
11:46:14 T:231964672 NOTICE: Thread DVDPlayer start, auto delete: false
11:46:14 T:231964672 NOTICE: Creating InputStream

11:46:14 T:231964672 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
11:46:14 T:231964672 DEBUG: ScanForExternalSubtitles: END (total time: 60 ms)
11:46:14 T:231964672 NOTICE: Creating Demuxer
11:46:14 T:231964672 DEBUG: Open - probing detected format [matroska,webm]
11:46:14 T:231964672 DEBUG: Open - avformat_find_stream_info starting
11:46:14 T:231964672 DEBUG: ffmpeg[DD38000]: [h264] Increasing reorder buffer to 1
11:46:14 T:215351296 DEBUG: ------ Window Init (DialogBusy.xml) ------
11:46:15 T:231964672 DEBUG: Open - av_find_stream_info finished

11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: encoder : libebml v1.2.3 + libmatroska v1.3.0
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: creation_time : 2013-07-27 05:48:32
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Duration: 01:30:22.76, start: 0.000000, bitrate: 5140 kb/s
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.0: start 0.000000, end 284.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 1
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.1: start 284.000000, end 700.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 2
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.2: start 700.000000, end 920.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 3
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.3: start 920.000000, end 1217.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 4
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.4: start 1217.000000, end 1476.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 5
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.5: start 1476.000000, end 1741.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 6
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.6: start 1741.000000, end 1994.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 7
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.7: start 1994.000000, end 2383.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 8
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.8: start 2383.000000, end 2647.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 9
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.9: start 2647.000000, end 2964.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 10
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.10: start 2964.000000, end 3307.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 11
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.11: start 3307.000000, end 3673.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 12
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.12: start 3673.000000, end 4186.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 13
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.13: start 4186.000000, end 4472.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 14
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.14: start 4472.000000, end 4796.000000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 15
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Chapter #0.15: start 4796.000000, end 5422.756000
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Metadata:
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: title : 16
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Stream #0:0(eng): Video: h264 (High), yuv420p(tv, bt709), 1910x810 [SAR 1:1 DAR 191:81], 23.98 fps, 23.98 tbr, 1k tbn, 2k tbc (default)
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Stream #0:1(eng): Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s (default)
11:46:15 T:231964672 INFO: ffmpeg[DD38000]: Stream #0:2(eng): Subtitle: subrip
11:46:15 T:231964672 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
11:46:15 T:231964672 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
11:46:15 T:231964672 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
11:46:15 T:231964672 NOTICE: Opening stream: 0 source: 256
11:46:15 T:231964672 NOTICE: Creating video codec with codec id: 28
11:46:15 T:231964672 DEBUG: CDVDFactoryCodec: compiled in hardware support: VideoToolBoxDecoder:yes AMCodec:no MediaCodec:no OpenMax:no libstagefright:no iMXVPU:no
11:46:15 T:231964672 DEBUG: FactoryCodec - Video: vtb - Opening
11:46:15 T:231964672 NOTICE: Open - using avcC atom of size(48), ref_frames(5)
11:46:15 T:231964672 DEBUG: FactoryCodec - Video: vtb-h264 - Opened
11:46:15 T:231964672 NOTICE: Creating video thread
11:46:15 T:234692608 NOTICE: Thread DVDPlayerVideo start, auto delete: false
11:46:15 T:234692608 NOTICE: running thread: video_thread
11:46:15 T:231964672 NOTICE: Opening stream: 1 source: 256
11:46:15 T:231964672 NOTICE: Finding audio codec for: 86019
11:46:15 T:231964672 DEBUG: FactoryCodec - Audio: passthrough - Opening
11:46:15 T:231964672 DEBUG: FactoryCodec - Audio: passthrough - Opened
11:46:15 T:231964672 NOTICE: Creating audio thread
11:46:15 T:238088192 NOTICE: Thread DVDPlayerAudio start, auto delete: false
11:46:15 T:238088192 NOTICE: running thread: CDVDPlayerAudio: Tongue

rocess()
11:46:15 T:234692608 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
11:46:15 T:238088192 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
11:46:15 T:231964672 NOTICE: Opening stream: 2 source: 256
11:46:15 T:231964672 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opening
11:46:15 T:231964672 DEBUG: FactoryCodec - Overlay: Text Subtitle Decoder - Opened
11:46:15 T:231964672 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local

11:46:15 T:231964672 DEBUG: OnPlayBackStarted: play state was 1, starting 1
11:46:15 T:231964672 DEBUG: CDVDPlayer::SetCaching - caching state 3
11:46:15 T:234692608 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
11:46:15 T:238088192 INFO: CAEStreamInfo::SyncAC3 - AC3 stream detected (6 channels, 48000Hz)
11:46:15 T:238088192 NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, pass-through)
11:46:15 T:238088192 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
11:46:15 T:234692608 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(83000.000000, 0)
11:46:15 T:216035328 INFO: CActiveAESink::OpenSink - initialize sink
11:46:15 T:804450304 WARNING: DARWINIOS: underflow (1476 vs 3764 bytes)
11:46:15 T:216035328 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
11:46:15 T:216035328 NOTICE: void CAAudioUnitSink: Confused

etCoreAudioPreferredSampleRate() requesting hw samplerate 48000.000000
11:46:15 T:216035328 NOTICE: void CAAudioUnitSink: Confused

etCoreAudioBuffersize() setting buffer duration to 0.021333
11:46:15 T:216035328 DEBUG: checkSessionProperties: volume = 0.500000, latency = 0.004500, buffer = 0.021333
11:46:15 T:216035328 NOTICE: bool CAAudioUnitSink: Confused

etupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 16-bit Signed Integer LE (48000Hz)
11:46:15 T:216035328 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized:
11:46:15 T:216035328 DEBUG: Output Device : Default
11:46:15 T:216035328 DEBUG: Sample Rate : 48000
11:46:15 T:216035328 DEBUG: Sample Format : AE_FMT_S16NE
11:46:15 T:216035328 DEBUG: Channel Count : 2
11:46:15 T:216035328 DEBUG: Channel Layout: FL,FR
11:46:15 T:216035328 DEBUG: Frames : 1024
11:46:15 T:216035328 DEBUG: Frame Samples : 2048
11:46:15 T:216035328 DEBUG: Frame Size : 4
11:46:15 T:204910592 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
11:46:15 T:231964672 DEBUG: Previous line repeats 1 times.
11:46:15 T:231964672 DEBUG: CDVDPlayer::HandleMessages - player started 1
11:46:15 T:234692608 NOTICE: fps: 23.976024, pwidth: 960, pheight: 406, dwidth: 960, dheight: 406
11:46:15 T:234692608 DEBUG: OutputPicture - change configuration. 960x406. framerate: 23.98. format: BGRA
11:46:15 T:234692608 NOTICE: Display resolution DESKTOP : 1280x720 (16)
11:46:15 T:234692608 DEBUG: CXBMCRenderManager::Configure - 2
11:46:15 T:231964672 DEBUG: CDVDPlayer::SetCaching - caching state 0
11:46:15 T:238088192 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(32000.000000, 1)
11:46:15 T:238088192 DEBUG: CDVDClock: Big Grin

iscontinuity - CDVDPlayerAudio::HandleSyncError1 - was:-129080.207797, should be:-129069.999464, error:10.208333
11:46:15 T:215351296 NOTICE: Using GL_TEXTURE_2D
11:46:15 T:215351296 DEBUG: GL: Requested render method: 0
11:46:15 T:215351296 NOTICE: GL: Using CoreVideoRef RGBA render method
11:46:15 T:215351296 NOTICE: GL: NPOT texture support detected
11:46:15 T:215351296 DEBUG: Activating window ID: 12005
11:46:15 T:215351296 DEBUG: ------ Window Deinit (Home.xml) ------
11:46:15 T:215351296 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
11:46:15 T:231964672 DEBUG: CDVDPlayer::HandleMessages - player started 2
11:46:15 T:215351296 DEBUG: PlayFile: OpenFile succeed, play state 2
11:46:15 T:215351296 DEBUG: OnPlayBackStarted: play state was 2, starting 0

11:46:16 T:215351296 DEBUG: Previous line repeats 1 times.
11:46:16 T:215351296 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
11:46:16 T:215351296 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
11:46:16 T:215351296 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
11:46:17 T:238088192 DEBUG: CDVDClock: Big Grin

iscontinuity - CDVDPlayerAudio::HandleSyncError2 - was:1892719.667203, should be:1880805.105235, error:-11914.561968
11:46:22 T:234692608 DEBUG: CPullupCorrection: detected pattern of length 1: 41708.33, frameduration: 41708.333333
11:46:35 T:1034767240 DEBUG: KodiController: Button press remoteAction = 1 originator = 1
11:46:35 T:215351296 DEBUG: CWinEventsIOS: Button press keyID = 6
11:46:35 T:215351296 NOTICE: CDVDPlayer::CloseFile()
11:46:35 T:215351296 NOTICE: DVDPlayer: waiting for threads to exit
11:46:35 T:231964672 NOTICE: CDVDPlayer::OnExit()
11:46:35 T:231964672 NOTICE: Closing stream player 1
11:46:35 T:231964672 NOTICE: Waiting for audio thread to exit
11:46:35 T:238088192 NOTICE: thread end: CDVDPlayerAudio::OnExit()
11:46:35 T:238088192 DEBUG: Thread DVDPlayerAudio 0 terminating
11:46:35 T:231964672 NOTICE: Closing audio device
11:46:35 T:231964672 NOTICE: Deleting audio codec
11:46:35 T:231964672 NOTICE: Closing stream player 2
11:46:35 T:231964672 NOTICE: waiting for video thread to exit
11:46:35 T:204910592 DEBUG: CActiveAE: Big Grin

iscardStream - audio stream deleted
11:46:35 T:204910592 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
11:46:35 T:216035328 DEBUG: Previous line repeats 1 times.
11:46:35 T:216035328 INFO: CActiveAESink::OpenSink - initialize sink
11:46:35 T:234692608 NOTICE: thread end: video_thread
11:46:35 T:234692608 DEBUG: Thread DVDPlayerVideo 0 terminating
11:46:35 T:231964672 NOTICE: deleting video codec
11:46:35 T:231964672 NOTICE: Closing stream player 3
11:46:35 T:231964672 DEBUG: CSMBFile::Close closing fd 10000
11:46:35 T:231964672 DEBUG: OnPlayBackStopped: play state was 2, starting 0
11:46:35 T:231964672 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
11:46:35 T:231964672 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
11:46:35 T:231964672 DEBUG: Thread DVDPlayer 0 terminating
11:46:35 T:215351296 NOTICE: DVDPlayer: finished waiting
11:46:35 T:215351296 DEBUG: LinuxRendererGL: Cleaning up GL resources
11:46:35 T:215351296 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Deactivate
11:46:35 T:215351296 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
11:46:36 T:216035328 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
11:46:36 T:216035328 NOTICE: void CAAudioUnitSink: Confused

etCoreAudioPreferredSampleRate() requesting hw samplerate 44100.000000
11:46:36 T:216035328 NOTICE: bool CAAudioUnitSink: Confused

etupAudio() couldn't set requested samplerate 44100, coreaudio will resample to 48000 instead
11:46:36 T:216035328 NOTICE: void CAAudioUnitSink: Confused

etCoreAudioBuffersize() setting buffer duration to 0.023220
11:46:36 T:216035328 DEBUG: checkSessionProperties: volume = 0.500000, latency = 0.004500, buffer = 0.021333
11:46:36 T:216035328 NOTICE: bool CAAudioUnitSink: Confused

etupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 16-bit Signed Integer LE (44100Hz)
11:46:36 T:215351296 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Activate new
11:46:36 T:215351296 DEBUG: ------ Window Init (Home.xml) ------
11:46:36 T:231964672 WARNING: DARWINIOS: underflow (0 vs 3764 bytes)
11:46:36 T:216035328 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized:
11:46:36 T:216035328 DEBUG: Output Device : Default
11:46:36 T:216035328 DEBUG: Sample Rate : 44100
11:46:36 T:216035328 DEBUG: Sample Format : AE_FMT_S16NE
11:46:36 T:216035328 DEBUG: Channel Count : 2
11:46:36 T:216035328 DEBUG: Channel Layout: FL,FR
11:46:36 T:216035328 DEBUG: Frames : 940
11:46:36 T:216035328 DEBUG: Frame Samples : 1880
11:46:36 T:216035328 DEBUG: Frame Size : 4
11:46:36 T:204910592 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
11:46:36 T:215351296 NOTICE: CDVDPlayer::CloseFile()
11:46:36 T:215351296 NOTICE: DVDPlayer: waiting for threads to exit
11:46:36 T:215351296 NOTICE: DVDPlayer: finished waiting
11:46:36 T:215351296 DEBUG: LinuxRendererGL: Cleaning up GL resources
11:46:36 T:215351296 NOTICE: CDVDPlayer::CloseFile()
11:46:36 T:215351296 NOTICE: DVDPlayer: waiting for threads to exit
11:46:36 T:215351296 NOTICE: DVDPlayer: finished waiting
11:46:36 T:215351296 DEBUG: LinuxRendererGL: Cleaning up GL resources
11:46:36 T:234692608 NOTICE: Thread JobWorker start, auto delete: true

11:46:37 T:222130176 DEBUG: RunQuery took 64 ms for 12 items query: select * from movieview WHERE (movieview.idFile IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
11:46:38 T:222130176 DEBUG: RunQuery took 288 ms for 110 items query: select * from movieview WHERE ((movieview.playCount IS NULL OR movieview.playCount = 0))

helta · 2015-12-13, 04:37

http://kodi.wiki/view/IOS_FAQ#No_sound_with_DTS_or_AC3

**Memphiz** · 2015-12-13, 09:16

Ensure that 16bit Audio is enabled in the ATV2 settings

b1nuzz · 2015-12-14, 10:17

Hi,
I have now transferred to an Amzon Fire TV box and have the same problem.
The AVR still receives Dolby Digital, DTS etc perfectly from other sources AND receives it OK from other applications installed on the Fire TV such as Netflix, but the problem of static still appears on KODI.

It must be something to do with the way it sends audio?

Any ideas?

Thanks again.