[Lambsilencer]

Hi guys

I am running the Nightly build kodi-20171014-2852c5e-Krypton-x86_64.dmg on High Sierra at the moment, which gives me the following problem:

When I start playback of a video file with the Apple Remote, everything works fine for a moment. Then, maybe 10 minutes in, the Apple Remote stops working - no commands are getting through. After another while, the picture freezes for about two seconds (Audio keeps playing), then, after the picture unfreezes and playback returns to normal, the Remote is working again.

I'm having this problem since I used this build: kodi-20170928-0b1ee74-high_sierra_remotebp-x86_64.dmg. This fixed my Up and Down button problem, but now seems to have this new one.

Here's the logfile, from the moment I started playback until I quit Kodi to get the logfile. The moment of the picture freeze is logged at 10:21:38.

Code:

10:00:05.661 T:140736046527296  NOTICE: VideoPlayer: Opening: /Users/lamb/TV/The.Blacklist.S05E03.Miss.Rebecca.Thrall.1080p.AMZN.WEB-DL.DDP5.1.H.264-NTb.mkv
10:00:05.661 T:140736046527296 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
10:00:05.661 T:123145504493568  NOTICE: Creating InputStream
10:00:05.662 T:123145504493568  NOTICE: Creating Demuxer
10:00:05.870 T:123145504493568  NOTICE: Opening stream: 0 source: 256
10:00:05.870 T:123145504493568  NOTICE: Creating video codec with codec id: 28
10:00:05.870 T:123145504493568  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
10:00:05.870 T:123145504493568  NOTICE: Creating video thread
10:00:05.870 T:123145504493568  NOTICE: Opening stream: 1 source: 256
10:00:05.870 T:123145504493568  NOTICE: Finding audio codec for: 86057
10:00:05.870 T:123145502347264  NOTICE: running thread: video_thread
10:00:05.870 T:123145504493568  NOTICE: Creating audio thread
10:00:05.871 T:123145504493568  NOTICE: Opening stream: 2 source: 256
10:00:05.871 T:123145505030144  NOTICE: running thread: CVideoPlayerAudio::Process()
10:00:05.884 T:123145505030144  NOTICE: Creating audio stream (codec id: 86057, channels: 6, sample rate: 48000, no pass-through)
10:00:06.075 T:123145503956992  NOTICE: virtual bool CAESinkDARWINOSX::Initialize(AEAudioFormat &, std::string &): Opening default device HDMI
10:00:06.129 T:123145503420416  NOTICE: CAEEncoderFFmpeg::Initialize - AC3 encoder ready
10:00:06.441 T:140736046527296  NOTICE: GL: Using CVBREF render method
10:00:06.469 T:140736046527296  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
10:00:06.474 T:140736046527296  NOTICE: GL: NPOT texture support detected
10:00:06.474 T:140736046527296  NOTICE: Using GL_TEXTURE_RECTANGLE_ARB
10:00:06.542 T:140736046527296  NOTICE: GL: Using CVBREF render method
10:00:06.542 T:140736046527296  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
10:00:06.544 T:140736046527296  NOTICE: GL: NPOT texture support detected
10:00:06.886 T:123145502347264  NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 41727
10:01:06.727 T:123145513156608  NOTICE: ES: Client  from 127.0.0.1 timed out
10:11:14.191 T:123145505030144  NOTICE: Previous line repeats 1 times.
10:11:14.191 T:123145505030144  NOTICE: CActiveAEStream::AddData - messy timestamps, increasing interval for measuring average error to 6000 ms
10:12:56.299 T:123145513156608  NOTICE: Previous line repeats 1 times.
10:12:56.299 T:123145513156608  NOTICE: ES: Client  from 127.0.0.1 timed out
10:21:38.246 T:123145502347264 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
10:22:33.639 T:140736046527296 WARNING: Previous line repeats 3 times.
10:22:33.657 T:140736046527296  NOTICE: CVideoPlayer::CloseFile()
10:22:33.657 T:140736046527296  NOTICE: VideoPlayer: waiting for threads to exit
10:22:33.667 T:123145504493568  NOTICE: CVideoPlayer::OnExit()
10:22:33.667 T:123145504493568  NOTICE: Closing stream player 1
10:22:33.667 T:123145504493568  NOTICE: Waiting for audio thread to exit
10:22:33.667 T:123145505030144   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
10:22:33.667 T:123145505030144  NOTICE: thread end: CVideoPlayerAudio::OnExit()
10:22:33.667 T:123145504493568  NOTICE: Closing audio device
10:22:33.683 T:123145504493568  NOTICE: Deleting audio codec
10:22:33.684 T:123145504493568  NOTICE: Closing stream player 2
10:22:33.684 T:123145504493568  NOTICE: waiting for video thread to exit
10:22:33.684 T:123145502347264   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
10:22:33.684 T:123145502347264  NOTICE: thread end: video_thread
10:22:33.684 T:123145504493568  NOTICE: deleting video codec
10:22:33.692 T:123145504493568  NOTICE: Closing stream player 3
10:22:33.693 T:140736046527296  NOTICE: VideoPlayer: finished waiting
10:22:34.138 T:123145503956992  NOTICE: Initialize pseudo device - requesting stream 0
10:22:34.140 T:140736046527296  NOTICE: CVideoPlayer::CloseFile()
10:22:34.140 T:140736046527296  NOTICE: VideoPlayer: waiting for threads to exit
10:22:34.140 T:140736046527296  NOTICE: VideoPlayer: finished waiting
10:22:34.140 T:140736046527296  NOTICE: CVideoPlayer::CloseFile()
10:22:34.140 T:140736046527296  NOTICE: VideoPlayer: waiting for threads to exit
10:22:34.140 T:140736046527296  NOTICE: VideoPlayer: finished waiting
10:22:39.998 T:140736046527296  NOTICE: Storing total System Uptime
10:22:39.998 T:140736046527296  NOTICE: Saving settings
10:22:40.001 T:140736046527296  NOTICE: Saving skin settings
10:22:40.003 T:140736046527296  NOTICE: stop all
10:22:40.062 T:140736046527296  NOTICE: stop player
10:22:40.072 T:140736046527296  NOTICE: ES: Stopping event server
10:22:40.076 T:140736046527296  NOTICE: stopping upnp
10:22:40.232 T:140736046527296  NOTICE: stopping zeroconf publishing
10:22:40.608 T:123145513156608  NOTICE: ES: UDP Event server stopped
10:22:40.938 T:140736046527296  NOTICE: stop dvd detect media
10:22:41.170 T:140736046527296  NOTICE: clean cached files!
10:22:41.367 T:140736046527296  NOTICE: closing down remote control service
10:22:41.367 T:140736046527296  NOTICE: stopped
10:22:41.568 T:140736046527296  NOTICE: destroy
10:22:41.568 T:140736046527296  NOTICE: unload skin
10:22:41.639 T:140736046527296  NOTICE: unload sections
10:22:41.643 T:140736046527296  NOTICE: special://profile/ is mapped to: special://masterprofile/
10:22:41.659 T:140736046527296  NOTICE: application stopped...

If you need more info (or more of the logfile), just tell me.

Greets
Lambsilencer

[DarrenHill]

Please do not post logs or log supports here. Upload the entire log to PasteBin.com or somewhere similar and supply their link here.

[Memphiz]

I need everything with "XBMCHelper" in it from the overall system log of osx.

The problem is unrelated to this test version. The only thing that was changed are the support for the 2 new button codes which were introduced by apple in high sierra.

[Lambsilencer]

Hi guys

Thanks a lot for your quick replies. I apologise for the log snippets in here.

I went to the system log and filtered for "XBMCHelper". Here's what I got:

Code:

https://pastebin.com/j1CUWK7r

If you have more questions, please don't hesitate. I'm new to the forum (even though I had a username for quite some time) and am watching countless hours of TV shows and movies on Kodi. It's the perfect tool for me, even with the occasional flaw.

Greets
Lambsilencer

[Lambsilencer]

Hi guys

I updated to the latest Nightly today (kodi-20171014-2852c5e-Krypton-x86_64.dmg) today and activated debug logging. And while watching an episode, the problem reappeared. From approx. 19:30 PM (can't say exactly, because I don't normally use the remote a lot while watching) until 19:49:50, the Apple Remote didn't work. Then, at 19:49:48, the picture froze for a few seconds and playback resumed normally, with the Apple Remote working again.

There is a line at 19:49:48 about the IR Receiver. You might want to check that out.

The logfile is here:

Code:

https://pastebin.com/eTZKvJAb

I hope this helps you further. If you need anything else, please just ask.

Greets
Lambsilencer

[Rechi]

https://pastebin.com/eTZKvJAb

This isn't a complete Debug log (wiki).

[Lambsilencer]

^^ Sorry about that.

Unfortunately, that logfile is no longer available. I'll have to wait for the next incident and will paste the entire logfile then. I've installed the Log Uploader Add-On, so everything should be fine then.

I'll keep you posted.

Greets
Lambsilencer

[Lambsilencer]

Hi guys

OK, it happened again. At around 22:05, I realised that the Apple Remote was not working anymore. And at 22:11:54, the picture froze for about three seconds, then playback resumed, with the Remote working again. I exited Kodi shortly after to get the logfile.

The full log from startup to shutdown is here:

https://pastebin.com/7Ra9gu59

Greets
Lambsilencer

[Memphiz]

there is a usb event at that time:

22:04:25.049 T:123145581150208 NOTICE: ES: Client from 127.0.0.1 timed out
22:11:54.073 T:140735991432000 DEBUG: USB Device Detach:IR Receiver, 488120320

Do you have any idea which device that is? Try to remove it from the mac and try without it if you can.

[Lambsilencer]

Hi,

That is the Apple Remote, which is an Infrared device. The IR Receiver is either the Remote itself or the built-in sensor which receives the signal on the Mac Mini. The Remote stopped working pretty much around 22:04 (don't know the exact time since I was watching an episode and only occasionally use the Remote to take a step back) until exactly that time when the USB Device Detach happens. From then on, the Remote is working again.

[Memphiz]

That would mean that the ir sensor is internally connected via USB ... well not sure what i should do about that tbh. I have no idea what happens internally in the mac at that point of time.

[Lambsilencer]

OK, then it seems that the problem is related to the latest OS, High Sierra, not Kodi. Then I'll have to wait for an update from Apple.

I think you can close this thread, then. Thanks for all your help!

Greets
Lambsilencer

[Amadeus]

@Lambsilencer Is this still causing you problems?  I've got a similar problem but not exactly the same.  My metal Apple remote stops working with the only way to get it working again is to restart the machine (a 2013-2014 MacMini).  If it was a Kodi issue then I'd have thought that a Kodi restart would fix it but i don't know if Kodi starts an additional processes at startup time which would not get restarted with a Kodi restart.
Because I've not been able to reproduce the problem on demand, I'm reluctant to just turn on debugging and posting the logs when it happens because I'd imagine they'd be huge, but happy to do so if required.

Thanks!