search

plexinc / plex-media-player

Next generation Plex Desktop/Embedded Client
GNU General Public License v2.0
1.17k stars 170 forks source link

High cpu usage when playing audio #678

Closed katafractari closed 6 years ago

katafractari commented 6 years ago

I noticed an unusually high cpu usage when playing music.

OS: (latest) Arch Linux CPU: i7 7500U (dual core with HP) PMP: 2.7.0 Music files are a mix of 320kbps MP3's and some albums in FLAC

CPU usage is around 30% when playing. After pausing the music it drops completely. CPU usage is the same if the app is maximized or minimized.

htop screenshot of PMP threads when playing a 320kbps MP3 below: screenshot from 2018-03-27 12-15-21

VLC and Chrome both use ~7% of CPU for playing the same file locally. This leads me to a question if PMP is doing some kind of client side transcoding?

Cheers.

LongChair commented 6 years ago

There is no client side transcoding that i know of.

There can be a few threads that are idle. only one seems to take a bunch of CPU.

It would be useful to get a PMP log and see if it's doing any kind of funky conversion depending on your configuration.

katafractari commented 6 years ago

Below is the log of PMP playing an MP3 file. Apparently there is a "direct play" involved. Hope it helps.

2018-03-27 22:09:00 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "4346",
    "key": "/library/metadata/4346",
      "playQueueItemID": "79099",
        "state": "paused",
          "hasMDE": 1,
            "time": 42000,
              "duration": 217000

}
2018-03-27 22:09:01 [ DEBUG  ] PowerComponent.cpp @ 58 - Disabling OS screensaver
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Run command: stop, flags=0, args=[]
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: EOF code: 4
2018-03-27 22:09:01 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "4346",
    "key": "/library/metadata/4346",
      "playQueueItemID": "79099",
        "state": "stopped",
          "hasMDE": 1,
            "continuing": 1,
              "time": 42000,
                "duration": 217000

}
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad: Uninit audio decoder.
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - af: Removing filter lavrresample
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: Terminating cache...
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: Cache exiting...
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: finished playback, success (reason 2)
2018-03-27 22:09:01 [ INFO   ] PlayerComponent.cpp @ 468 - Entering state: canceled
2018-03-27 22:09:01 [ INFO   ] PlayerComponent.cpp @ 589 - cplayer:
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: draining...
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 20 - "DisplayManager found 1 Display(s)."
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 26 - "Available modes for Display #0 (eDP-1)"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  0:  1920 x 1080 x  0bpp @60.0119Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  1:  1920 x 1080 x  0bpp @60.0078Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  2:  1920 x 1080 x  0bpp @59.9685Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  3:  1920 x 1080 x  0bpp @59.9628Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  4:  1920 x 1080 x  0bpp @59.9339Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  5:  1920 x 1080 x  0bpp @48.0095Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  6:  1680 x 1050 x  0bpp @59.9543Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  7:  1680 x 1050 x  0bpp @59.8833Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  8:  1400 x 1050 x  0bpp @59.9756Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode  9:  1600 x  900 x  0bpp @59.9883Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 10:  1600 x  900 x  0bpp @59.9416Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 11:  1600 x  900 x  0bpp @59.946Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 12:  1600 x  900 x  0bpp @59.8248Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 13:  1280 x 1024 x  0bpp @60.0197Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 14:  1400 x  900 x  0bpp @59.9641Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 15:  1400 x  900 x  0bpp @59.8798Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 16:  1280 x  960 x  0bpp @60Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 17:  1440 x  810 x  0bpp @59.996Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 18:  1440 x  810 x  0bpp @59.9746Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 19:  1368 x  768 x  0bpp @59.882Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 20:  1368 x  768 x  0bpp @59.8532Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 21:  1280 x  800 x  0bpp @59.9866Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 22:  1280 x  800 x  0bpp @59.9716Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 23:  1280 x  800 x  0bpp @59.8103Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 24:  1280 x  800 x  0bpp @59.9095Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 25:  1280 x  720 x  0bpp @60.0008Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 26:  1280 x  720 x  0bpp @59.9911Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 27:  1280 x  720 x  0bpp @59.8551Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 28:  1280 x  720 x  0bpp @59.7447Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 29:  1024 x  768 x  0bpp @60.0373Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 30:  1024 x  768 x  0bpp @60.0038Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 31:   960 x  720 x  0bpp @60Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 32:   928 x  696 x  0bpp @60.0507Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 33:   896 x  672 x  0bpp @60.0144Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 34:  1024 x  576 x  0bpp @59.9534Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 35:  1024 x  576 x  0bpp @59.9593Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 36:  1024 x  576 x  0bpp @59.8992Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 37:  1024 x  576 x  0bpp @59.8195Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 38:   960 x  600 x  0bpp @59.9327Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 39:   960 x  600 x  0bpp @59.9988Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 40:   960 x  540 x  0bpp @59.9628Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 41:   960 x  540 x  0bpp @59.9879Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 42:   960 x  540 x  0bpp @59.629Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 43:   960 x  540 x  0bpp @59.8182Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 44:   800 x  600 x  0bpp @60Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 45:   800 x  600 x  0bpp @60.3165Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 46:   800 x  600 x  0bpp @56.25Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 47:   840 x  525 x  0bpp @60.0094Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 48:   840 x  525 x  0bpp @59.8833Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 49:   864 x  486 x  0bpp @59.9153Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 50:   864 x  486 x  0bpp @59.5703Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 51:   700 x  525 x  0bpp @59.9756Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 52:   800 x  450 x  0bpp @59.946Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 53:   800 x  450 x  0bpp @59.8248Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 54:   640 x  512 x  0bpp @60.0197Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 55:   700 x  450 x  0bpp @59.9641Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 56:   700 x  450 x  0bpp @59.8798Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 57:   640 x  480 x  0bpp @60Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 58:   640 x  480 x  0bpp @59.9405Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 59:   720 x  405 x  0bpp @59.5062Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 60:   720 x  405 x  0bpp @58.9879Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 61:   684 x  384 x  0bpp @59.882Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 62:   684 x  384 x  0bpp @59.8532Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 63:   640 x  400 x  0bpp @59.8824Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 64:   640 x  400 x  0bpp @59.9824Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 65:   640 x  360 x  0bpp @59.8551Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 66:   640 x  360 x  0bpp @59.8255Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 67:   640 x  360 x  0bpp @59.8404Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 68:   640 x  360 x  0bpp @59.3249Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 69:   512 x  384 x  0bpp @60.0038Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 70:   512 x  288 x  0bpp @59.9994Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 71:   512 x  288 x  0bpp @59.9206Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 72:   480 x  270 x  0bpp @59.629Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 73:   480 x  270 x  0bpp @59.8182Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 74:   400 x  300 x  0bpp @60.3165Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 75:   400 x  300 x  0bpp @56.3401Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 76:   432 x  243 x  0bpp @59.9153Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 77:   432 x  243 x  0bpp @59.5703Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 78:   320 x  240 x  0bpp @60.0525Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 79:   360 x  202 x  0bpp @59.5062Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 80:   360 x  202 x  0bpp @59.129Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 81:   320 x  180 x  0bpp @59.8404Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 30 - "Mode 82:   320 x  180 x  0bpp @59.3249Hz"
2018-03-27 22:09:01 [ INFO   ] DisplayManager.cpp @ 40 - "DisplayManager : Current Display Mode on Display #0 is  1920 x 1080 x  0bpp @60.0119Hz"
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-sync="audio" -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: hwdec="no" -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: videotoolbox-format="nv12" -> -3
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: deinterlace="no" -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: display-fps=60.011883 -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-delay=0.000000 -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: cache=76800 -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-unscaled=false -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-aspect="-1" -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: keepaspect=true -> 1
2018-03-27 22:09:01 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: panscan=0.000000 -> 1
2018-03-27 22:09:01 [ INFO   ] JS: %c[MDE] Augmented profile: {
    "directPlay": {
        "mp4": {},
            "mp3": {},
                "ogg": {},
                    "wav": {},
                        "flac": {},
                            "dts": {},
                                "ac3": {},
                                    "aac": {},
                                        "aiff": {},
                                            "asf": {}

    },
      "directStream": {}

}
2018-03-27 22:09:01 [ INFO   ] JS: %c[MDE] Starting analysis of 322 (mp3, mp3)
2018-03-27 22:09:01 [ INFO   ] JS: %c[MDE] Analyzing direct play
2018-03-27 22:09:01 [ INFO   ] JS: %c[MDE] Analyzing audio direct stream
2018-03-27 22:09:01 [ INFO   ] JS: %c[MDE] Analyzing playability
2018-03-27 22:09:01 [ INFO   ] JS: [MDE] Finished analysis of 322 (mp3, mp3)
2018-03-27 22:09:01 [ INFO   ] JS: %ccanPlay: true
2018-03-27 22:09:01 [ INFO   ] JS: %ccanDirectPlay: true
2018-03-27 22:09:01 [ INFO   ] JS: %ccanDirectStreamAudio: true
2018-03-27 22:09:01 [ INFO   ] JS: %cbitrate: 322
2018-03-27 22:09:01 [ INFO   ] JS: console.groupEnd
2018-03-27 22:09:01 [ INFO   ] JS: [PDE] Player decision
2018-03-27 22:09:01 [ INFO   ] JS: %cPlayer: html
2018-03-27 22:09:01 [ INFO   ] JS: %cProtocol: http
2018-03-27 22:09:01 [ INFO   ] JS: %cDirect Play: true
2018-03-27 22:09:01 [ INFO   ] JS: console.groupEnd
2018-03-27 22:09:01 [ INFO   ] JS: [Transcoder] Music (decision) options
2018-03-27 22:09:01 [ INFO   ] JS: %chasMDE: 1
2018-03-27 22:09:01 [ INFO   ] JS: %cpath: /library/metadata/2692
2018-03-27 22:09:01 [ INFO   ] JS: %cmediaIndex: 0
2018-03-27 22:09:01 [ INFO   ] JS: %cpartIndex: 0
2018-03-27 22:09:01 [ INFO   ] JS: %cdirectStream: 1
2018-03-27 22:09:01 [ INFO   ] JS: %cX-Plex-Session-Identifier: 0j2ev4ujq3aty8fl5oolqgwm
2018-03-27 22:09:01 [ INFO   ] JS: %csession: m86sger68m8pavecnu9u69uy
2018-03-27 22:09:01 [ INFO   ] JS: %cprotocol: http
2018-03-27 22:09:01 [ INFO   ] JS: %cdirectPlay: 1
2018-03-27 22:09:01 [ INFO   ] JS: %coffset: 0
2018-03-27 22:09:01 [ INFO   ] JS: %ccopyts: 0
2018-03-27 22:09:01 [ INFO   ] JS: console.groupEnd
2018-03-27 22:09:01 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1

}
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: volume=100 -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 359 - Video framerate: 0 fps
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 363 - Not switching refresh-rate (disabled by settings).
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 609 - checking codecs
2018-03-27 22:09:02 [ INFO   ] CodecsComponent.cpp @ 1260 - Using system audio decoders: true
2018-03-27 22:09:02 [ INFO   ] CodecsComponent.cpp @ 1261 - Using system video decoders: false
2018-03-27 22:09:02 [ INFO   ] CodecsComponent.cpp @ 1264 - Not using on-demand codecs.
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 611 - resuming loading
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Run command: stop, flags=0, args=[]
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-sync="audio" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: hwdec="no" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: videotoolbox-format="nv12" -> -3
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: deinterlace="no" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: display-fps=60.011883 -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-delay=0.000000 -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: cache=76800 -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-unscaled=false -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-aspect="-1" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: keepaspect=true -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: panscan=0.000000 -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Run command: loadfile, flags=0, args=[https://192.168.1.2:32400/library/parts/2272/1421359434/file.mp3?X-Plex-Session-Identifier=0j2ev4ujq3aty8fl5oolqgwm&X-Plex-Client-Identifier=ga6dy86sqgipltjguzhk9hc5&X-Plex-Device-Screen-Resolution=1280x720%2C960x540&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A2%7D%2Cac3%7Bbitrate%3A800000%26channels%3A2%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.dev-608417d4&X-Plex-Device=Linux&X-Plex-Device-Name=metamatic&X-Plex-Model=unknown&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=en, append-play, ad=,aid=no,pause=no,sid=no,stream-lavf-o=verifyhost=192-168-1-2.4d0fc2887625438c962d3114a37c5e9c.plex.direct,vd=,vid=no]
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'ad' = '' (flags = 80)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'aid' = 'no' (flags = 80)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'pause' = 'no' (flags = 80)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'sid' = 'no' (flags = 80)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'stream-lavf-o' = 'verifyhost=192-168-1-2.4d0fc2887625438c962d3114a37c5e9c.plex.direct' (flags = 80)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'vd' = '' (flags = 80)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'vid' = 'no' (flags = 80)
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - cplayer: Playing: https://192.168.1.2:32400/library/parts/2272/1421359434/file.mp3?X-Plex-Session-Identifier=0j2ev4ujq3aty8fl5oolqgwm&X-Plex-Client-Identifier=ga6dy86sqgipltjguzhk9hc5&X-Plex-Device-Screen-Resolution=1280x720%2C960x540&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A2%7D%2Cac3%7Bbitrate%3A800000%26channels%3A2%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.dev-608417d4&X-Plex-Device=Linux&X-Plex-Device-Name=metamatic&X-Plex-Model=unknown&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=en
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Running hook: main/on_load
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: ad="mp3" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: vd="mp3" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Run command: hook-ack, flags=0, args=[on_load]
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ffmpeg: Opening https://192.168.1.2:32400/library/parts/2272/1421359434/file.mp3?X-Plex-Session-Identifier=0j2ev4ujq3aty8fl5oolqgwm&X-Plex-Client-Identifier=ga6dy86sqgipltjguzhk9hc5&X-Plex-Device-Screen-Resolution=1280x720%2C960x540&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A2%7D%2Cac3%7Bbitrate%3A800000%26channels%3A2%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.dev-608417d4&X-Plex-Device=Linux&X-Plex-Device-Name=metamatic&X-Plex-Model=unknown&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=en
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 459 - Entering state: buffering
2018-03-27 22:09:02 [ INFO   ] JS: %c[Player] Buffering detected by PMP
2018-03-27 22:09:02 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1

}
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ffmpeg: Mime-type: 'audio/mpeg'
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ffmpeg: Stream opened successfully.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: no backbuffer needed
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: Cache size set to 16069 KiB (32 KiB backbuffer)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - demux: Trying demuxers for level=normal.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - lavf: Found 'mp3' at score=1 size=2048.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - lavf: Found 'mp3' at score=25 size=4096.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: Dropping cache at pos 16422680, cached range: 0-286720.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: Seeking underlying stream: 286720 -> 16422680
2018-03-27 22:09:02 [ WARN   ] PlayerComponent.cpp @ 591 - ffmpeg: Invalid return value 0 for stream protocol
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: EOF reached.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: Dropping cache at pos 0, cached range: 16422680-16422808.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: Seeking underlying stream: 16422808 -> 0
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ffmpeg/demuxer: mp3: Skipping 20 bytes of junk at 0.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ffmpeg/demuxer: mp3: max_analyze_duration 500000 reached at 522449 microseconds st:0
2018-03-27 22:09:02 [ WARN   ] PlayerComponent.cpp @ 591 - ffmpeg/demuxer: mp3: Estimating duration from bitrate, this may be inaccurate
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - lavf: avformat_find_stream_info() finished after 32768 bytes.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - demux: Detected file format: mp3 (libavformat)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: blocking for STREAM_CTRL 6
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Opening done: https://192.168.1.2:32400/library/parts/2272/1421359434/file.mp3?X-Plex-Session-Identifier=0j2ev4ujq3aty8fl5oolqgwm&X-Plex-Client-Identifier=ga6dy86sqgipltjguzhk9hc5&X-Plex-Device-Screen-Resolution=1280x720%2C960x540&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A2%7D%2Cac3%7Bbitrate%3A800000%26channels%3A2%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.dev-608417d4&X-Plex-Device=Linux&X-Plex-Device-Name=metamatic&X-Plex-Model=unknown&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=en
2018-03-27 22:09:02 [ INFO   ] CodecsComponent.cpp @ 1260 - Using system audio decoders: true
2018-03-27 22:09:02 [ INFO   ] CodecsComponent.cpp @ 1261 - Using system video decoders: false
2018-03-27 22:09:02 [ INFO   ] CodecsComponent.cpp @ 1264 - Not using on-demand codecs.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - global: config path: 'sub' -/-> '/home/rok/.local/share/plexmediaplayer/sub'
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Running hook: main/on_preloaded
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: sid="no" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: aid="1" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: ad="mp3" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Set property: vd="mp3" -> 1
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Run command: hook-ack, flags=0, args=[on_preloaded]
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - cplayer:  (+) Audio --aid=1 (mp3 2ch 44100Hz)
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - display-tags: File tags:
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - display-tags:  Artist: Moveknowledgement
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - display-tags:  Album: Pump Down!!!
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - display-tags:  Genre: Other
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - display-tags:  Title: Hands
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - display-tags:  Track: 4
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad: Codec list:
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad:     mp3 - MP3 (MPEG audio layer 3)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad:     mp3 - MP3 (MPEG audio layer 3)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad:     mp3float (mp3) - MP3 (MPEG audio layer 3)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad: Opening audio decoder mp3
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad: Requesting 1 threads for decoding.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ad: Selected audio codec: mp3 (MP3 (MPEG audio layer 3))
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: Starting playback...
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af: Audio filter chain:
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af:   [in] 44100Hz stereo 2ch s16p
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af:   [out] 44100Hz stereo 2ch s16p
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af:   [ao] 44100Hz stereo 2ch s16p
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao: Trying audio driver 'pulse'
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: requested format: 44100 Hz, stereo channels, s16p
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: Library version: 11.1.0
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: Proto: 32
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: Server proto: 4294967295
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: Channel layouts:
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #fl
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #fr
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #fc
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #lfe
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #bl
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #br
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #flc
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #frc
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #bc
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #sl
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #sr
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #tc
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #tfl
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #tfc
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #tfr
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #tbl
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #tbc
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse:  - #tbr
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: result: stereo
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: device buffer: 5513 samples.
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - ao/pulse: using soft-buffer of 8820 samples.
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 589 - cplayer: AO: [pulse] 44100Hz stereo 2ch s16
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: AO: Description: PulseAudio audio output
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af: Adding filter lavrresample
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af: Setting option 'normalize' = 'yes' (flags = 0)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af: Setting option 'o' = 'surround_mix_level=1' (flags = 0)
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af: Audio filter chain:
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af:   [in] 44100Hz stereo 2ch s16p
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af:   [lavrresample] 44100Hz stereo 2ch s16 [a]
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af:   [out] 44100Hz stereo 2ch s16
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - af:   [ao] 44100Hz stereo 2ch s16
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: starting audio playback
2018-03-27 22:09:02 [ DEBUG  ] PlayerComponent.cpp @ 587 - cplayer: playback restart complete
2018-03-27 22:09:02 [ INFO   ] PlayerComponent.cpp @ 455 - Entering state: playing
2018-03-27 22:09:02 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1

}
2018-03-27 22:09:03 [ WARN   ] PlayerComponent.cpp @ 591 - ffmpeg: Invalid return value 0 for stream protocol
2018-03-27 22:09:03 [ DEBUG  ] PlayerComponent.cpp @ 587 - cache: EOF reached.
2018-03-27 22:09:12 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 9000,
              "duration": 410000

}
2018-03-27 22:09:22 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 19000,
              "duration": 410000

}
2018-03-27 22:09:32 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 29000,
              "duration": 410000

}
2018-03-27 22:09:42 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 39000,
              "duration": 410000

}
2018-03-27 22:09:52 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 49000,
              "duration": 410000

}
2018-03-27 22:10:02 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 59000,
              "duration": 410000

}
2018-03-27 22:10:12 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 69000,
              "duration": 410000

}
2018-03-27 22:10:22 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 79000,
              "duration": 410000

}
2018-03-27 22:10:32 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 89000,
              "duration": 410000

}
2018-03-27 22:10:42 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 99000,
              "duration": 410000

}

k2018-03-27 22:10:52 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 109000,
              "duration": 410000

}
^[kkkjjkkj
2018-03-27 22:11:02 [ INFO   ] JS: %c[Player] Timeline, {
  "ratingKey": "2692",
    "key": "/library/metadata/2692",
      "playQueueItemID": "79100",
        "state": "playing",
          "hasMDE": 1,
            "time": 119000,
              "duration": 410000

}

]
kinglouie commented 6 years ago

I have the same issue with PMP 2.7.0 on macOS 10.13. CPU usage is around 50% when music is playing and drop only when playback is paused.

Logfile ``` 2018-03-29 12:27:26 [ INFO ] Log.cpp @ 115 - Starting Plex Media Player version: 2.7.0.806-608417d4 build date: 2018-03-20 2018-03-29 12:27:26 [ INFO ] Log.cpp @ 116 - Running on: macOS 10.13 [17.3.0] arch x86_64 2018-03-29 12:27:26 [ INFO ] Log.cpp @ 117 - Qt Version: 5.9.0 [x86_64-little_endian-lp64] 2018-03-29 12:27:26 [ DEBUG ] UpdateManager.cpp @ 59 - Checking for: "/Users/mh/Library/Caches/Plex Media Player/updates/2.7.0.806-608417d4/_readyToApply" 2018-03-29 12:27:26 [ DEBUG ] UpdateManager.cpp @ 78 - No valid/applicable update found. 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: settings inited 2018-03-29 12:27:26 [ DEBUG ] HTTPServer.cpp @ 32 - Listening to port: 32433 2018-03-29 12:27:26 [ INFO ] InputMapping.cpp @ 113 - Loading inputmaps from: ":/inputmaps" 2018-03-29 12:27:26 [ WARN ] InputMapping.cpp @ 92 - Missing element 'idmatcher' from mapping file: ":/inputmaps/dualshock4-xbox-emulate.json" 2018-03-29 12:27:26 [ INFO ] InputMapping.cpp @ 113 - Loading inputmaps from: "/Users/mh/Library/Application Support/Plex Media Player/inputmaps" 2018-03-29 12:27:26 [ INFO ] InputComponent.cpp @ 54 - Successfully inited input: Keyboard 2018-03-29 12:27:26 [ INFO ] LocalJsonServer.cpp @ 33 - Listening to socket: "/tmp/pmp_inputSocket_mh.sock" 2018-03-29 12:27:26 [ INFO ] InputComponent.cpp @ 54 - Successfully inited input: socket 2018-03-29 12:27:26 [ INFO ] InputComponent.cpp @ 54 - Successfully inited input: roku 2018-03-29 12:27:26 [ INFO ] InputComponent.cpp @ 54 - Successfully inited input: AppleRemote 2018-03-29 12:27:26 [ INFO ] InputComponent.cpp @ 54 - Successfully inited input: AppleMediaKeys 2018-03-29 12:27:26 [ INFO ] InputSDL.cpp @ 207 - SDL found 0 joysticks 2018-03-29 12:27:26 [ INFO ] InputComponent.cpp @ 54 - Successfully inited input: SDL 2018-03-29 12:27:26 [ INFO ] InputCEC.cpp @ 112 - libCEC was successfully initialized, found version 262146 2018-03-29 12:27:26 [ INFO ] InputComponent.cpp @ 54 - Successfully inited input: CEC 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: input inited 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: system inited 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 20 - "DisplayManager found 2 Display(s)." 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 26 - "Available modes for Display #0 (Display 0)" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 0: 3840 x 2160 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 1: 3840 x 2160 x 32bpp @30Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 2: 1024 x 768 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 3: 800 x 600 x 32bpp @60.25Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 4: 640 x 480 x 32bpp @59.875Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 5: 1280 x 1024 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 6: 1920 x 1080 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 7: 1920 x 1080 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 8: 1280 x 720 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 9: 720 x 480 x 32bpp @59.875Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 10: 640 x 480 x 32bpp @59.875Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 11: 2560 x 1440 x 32bpp @59.875Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 12: 1024 x 576 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 13: 1280 x 960 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 14: 1344 x 1008 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 15: 1344 x 756 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 16: 1600 x 1200 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 17: 1600 x 900 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 18: 2048 x 1152 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 19: 2304 x 1296 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 20: 3008 x 1692 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 21: 3200 x 1800 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 22: 3360 x 1890 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 26 - "Available modes for Display #1 (Display 1)" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 0: 2880 x 1800 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 1: 1440 x 900 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 2: 2560 x 1600 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 3: 2048 x 1280 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 4: 1024 x 768 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 5: 800 x 600 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 6: 640 x 480 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 7: 1680 x 1050 x 32bpp @60Hz" 2018-03-29 12:27:26 [ INFO ] DisplayManager.cpp @ 30 - "Mode 8: 1280 x 800 x 32bpp @60Hz" 2018-03-29 12:27:26 [ ERROR ] DisplayManager.cpp @ 44 - DisplayManager : unable to retrieve current video mode 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: display inited 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: updater inited 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: remote inited 2018-03-29 12:27:26 [ DEBUG ] SettingsComponent.cpp @ 140 - Updated possible values for: "device" to (QVariant(QVariantMap, QMap(("title", QVariant(QString, "Autoselect device"))("value", QVariant(QString, "auto")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "Built-in Output"))("value", QVariant(QString, "coreaudio/AppleHDAEngineOutput:1B,0,1,1:0")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "DisplayPort"))("value", QVariant(QString, "coreaudio/AppleHDAEngineOutputDP:3,0,1,1:0:{6D1E-5B09-00015569}"))))) 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 921 - Audio devices removed: QSet() 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 922 - Audio devices added: QSet("auto", "coreaudio/AppleHDAEngineOutput:1B,0,1,1:0", "coreaudio/AppleHDAEngineOutputDP:3,0,1,1:0:{6D1E-5B09-00015569}") 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 923 - Audio device selected: "auto" 2018-03-29 12:27:26 [ INFO ] PlayerComponent.cpp @ 1078 - Audio Config - device: auto, channel layout: auto, passthrough codecs: none, ac3 transcoding: no 2018-03-29 12:27:26 [ INFO ] PlayerComponent.cpp @ 1215 - Codec "mpeg2video" present 2018-03-29 12:27:26 [ INFO ] PlayerComponent.cpp @ 1215 - Codec "vc1" present 2018-03-29 12:27:26 [ INFO ] PlayerComponent.cpp @ 219 - Present codecs: mp3 png apng mjpeg thp gif dirac ffv1 ffvhuff huffyuv rawvideo ayuv r210 v210 v210x v308 v408 v410 y41p yuv4 ansi alac flac vorbis opus pcm_f32be pcm_f32le pcm_f64be pcm_f64le pcm_lxf pcm_s16be pcm_s16be_planar pcm_s16le pcm_s16le_planar pcm_s24be pcm_s24le pcm_s24le_planar pcm_s32be pcm_s32le pcm_s32le_planar pcm_s8 pcm_s8_planar pcm_u16be pcm_u16le pcm_u24be pcm_u24le pcm_u32be pcm_u32le pcm_u8 pcm_alaw pcm_mulaw 012v aac_at eac3_at eac3_eae truehd_eae mlp_eae eac3_eae(enc) 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: player inited 2018-03-29 12:27:26 [ INFO ] ComponentManager.cpp @ 41 - Component: power inited 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: switch mapped to DisplayComponent::switchCommand 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 264 - Host command maps to method with an argument. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: recreateRpiUI mapped to DisplayManagerOSX::resetRendering 2018-03-29 12:27:26 [ ERROR ] InputComponent.cpp @ 273 - Slot for host command missing, or has incorrect signature! 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: player mapped to PlayerComponent::userCommand 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 264 - Host command maps to method with an argument. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: poweroff mapped to PowerComponent::PowerOff 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: reboot mapped to PowerComponent::Reboot 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: suspend mapped to PowerComponent::Suspend 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: cycle_setting mapped to SettingsComponent::cycleSettingCommand 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 264 - Host command maps to method with an argument. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: set_setting mapped to SettingsComponent::setSettingCommand 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 264 - Host command maps to method with an argument. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: crash! mapped to SystemComponent::crashApp 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: script mapped to SystemComponent::runUserScript 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 264 - Host command maps to method with an argument. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: message mapped to SystemComponent::hostMessage 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 264 - Host command maps to method with an argument. 2018-03-29 12:27:26 [ DEBUG ] SettingsComponent.cpp @ 783 - () 2018-03-29 12:27:26 [ DEBUG ] HelperLauncher.cpp @ 52 - Connecting to helper 2018-03-29 12:27:26 [ DEBUG ] HelperLauncher.cpp @ 148 - Connected to helper 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: close mapped to KonvergoWindow::close 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: toggleDebug mapped to KonvergoWindow::toggleDebug 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: reload mapped to KonvergoWindow::reloadWeb 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: fullscreen mapped to KonvergoWindow::toggleFullscreen 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: minimize mapped to KonvergoWindow::minimizeWindow 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 255 - Adding host command: fullscreenCurrentMode mapped to KonvergoWindow::toggleFullscreenNoSwitch 2018-03-29 12:27:26 [ DEBUG ] InputComponent.cpp @ 269 - Host command maps to method without arguments. 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 276 - Restoring geo: QRect(-1920,107 1920x1155) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 198 - Window state when saving geometry: QWindow::Visibility(Hidden) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 209 - Saving window geometry: QRect(-1920,107 1920x1155) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 513 - QWindow visibility set to QWindow::Visibility(Windowed) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 643 - resize event: QSize(1920, 1155) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 276 - Restoring geo: QRect(-1920,107 1920x1155) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 198 - Window state when saving geometry: QWindow::Visibility(Windowed) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 209 - Saving window geometry: QRect(-1920,107 1920x1155) 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 732 - Screen 0 QScreen(0x60400001fc10, name="LG Ultra HD") QRect(0,0 3008x1692) QRect(-1920,0 4928x1692) active: false selected: false 2018-03-29 12:27:26 [ DEBUG ] KonvergoWindow.cpp @ 732 - Screen 1 QScreen(0x608000202260, name="Color LCD") QRect(-1920,62 1920x1200) QRect(-1920,0 4928x1692) active: true selected: false 2018-03-29 12:27:26 [ DEBUG ] SettingsComponent.cpp @ 140 - Updated possible values for: "forceFSScreen" to (QVariant(QVariantMap, QMap(("title", QVariant(QString, "Auto"))("value", QVariant(QString, "")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "0,0 3007x1691 (LG Ultra HD)"))("value", QVariant(QString, "LG Ultra HD")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "-1920,62 -1x1261 (Color LCD) *"))("value", QVariant(QString, "Color LCD"))))) 2018-03-29 12:27:26 [ DEBUG ] SettingsComponent.cpp @ 775 - Using web-client URL: "file:////Applications/Plex Media Player.app/Contents/MacOS/../Resources/web-client/desktop/index.html" 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: display to webchannel 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: input to webchannel 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: player to webchannel 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: power to webchannel 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: remote to webchannel 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: settings to webchannel 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: system to webchannel 2018-03-29 12:27:26 [ DEBUG ] ComponentManager.cpp @ 88 - Adding component: updater to webchannel 2018-03-29 12:27:26 [ INFO ] Log.cpp @ 91 - Setting log level to: "debug" 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - cplayer: Warning: option --hwdec-preload was replaced with --opengl-hwdec-interop and might be removed in the future. 2018-03-29 12:27:26 [ DEBUG ] SettingsComponent.cpp @ 140 - Updated possible values for: "device" to (QVariant(QVariantMap, QMap(("title", QVariant(QString, "Autoselect device"))("value", QVariant(QString, "auto")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "Built-in Output"))("value", QVariant(QString, "coreaudio/AppleHDAEngineOutput:1B,0,1,1:0")))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "DisplayPort"))("value", QVariant(QString, "coreaudio/AppleHDAEngineOutputDP:3,0,1,1:0:{6D1E-5B09-00015569}"))))) 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 921 - Audio devices removed: QSet() 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 922 - Audio devices added: QSet() 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 923 - Audio device selected: "auto" 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: mpv 53a7eec (C) 2000-2017 mpv/MPlayer/mplayer2 projects 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: built on Wed Aug 30 05:14:14 PDT 2017 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: ffmpeg library versions: 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: libavutil 55.62.100 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: libavcodec 57.92.100 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: libavformat 57.72.100 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: libswscale 4.7.100 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: libavfilter 6.84.101 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: libswresample 2.8.100 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: ffmpeg version: git-2017-06-28-97a6171-,97a6171-pmp-260 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Configuration: ./waf configure --prefix=/Volumes/CI-OSX/jenkins/konvergo-deps-codecs/BUILD_TAG/darwin-x86_64/output/konvergo-codecs-depends-darwin-x86_64-release-2603aad --enable-libmpv-shared --enable-static-build --disable-cplayer --disable-apple-remote --disable-libavdevice --disable-debug-build 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: List of enabled features: asm atomics avutil-content-light-level avutil-imgcpy-uc bsd-fstatfs build-date cocoa coreaudio cplugins cuda-hwaccel encoding fchmod gl gl-cocoa glob glob-posix gnuc iconv is_ffmpeg libass libass-osd libav libavcodec libdl libm libmpv-shared macos-touchbar nanosleep optimize osx-thread-name plain-gl posix posix-or-mingw posix-spawn pthreads shm static-build stdatomic termios uchardet videotoolbox-gl videotoolbox-hwaccel videotoolbox-hwaccel-old zlib 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: '' -> '/Users/mh/Library/Application Support/Plex Media Player' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: 'encoding-profiles.conf' -/-> '/Users/mh/Library/Application Support/Plex Media Player/encoding-profiles.conf' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: 'mpv.conf' -/-> '/Users/mh/Library/Application Support/Plex Media Player/mpv.conf' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: 'config' -/-> '/Users/mh/Library/Application Support/Plex Media Player/config' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: 'input.conf' -/-> '/Users/mh/Library/Application Support/Plex Media Player/input.conf' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - ffmpeg: Loading external lib /Users/mh/Library/Application Support/Plex Media Player/Codecs/97a6171-pmp-260-darwin-x86_64/libmp3_decoder.dylib 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - osx: Exiting... 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: 'scripts' -> '/Users/mh/Library/Application Support/Plex Media Player/scripts' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: hook-add, flags=0, args=[on_load, 1, 0] 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: hook-add, flags=0, args=[on_preloaded, 2, 0] 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: selected audio output device: DisplayPort (58) 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-exclusive=false -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-device="auto" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: af-defaults="lavrresample:normalize=yes:o=[surround_mix_level=1]" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-spdif="" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-channels="auto" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: af, flags=0, args=[del, @ac3] 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - cplayer: Option af-del: item label @ac3 not found. 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - cplayer: Warning: property 'sub-text-font-size' was replaced with 'sub-font-size' and might be removed in the future. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-font-size=32.000000 -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-text-font-size=32.000000 -> 1 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - cplayer: Warning: property 'sub-text-color' was replaced with 'sub-color' and might be removed in the future. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-color="#EEEEEE" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-text-color="#EEEEEE" -> 1 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - cplayer: Warning: property 'sub-text-border-color' was replaced with 'sub-border-color' and might be removed in the future. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-border-color="#000000" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-text-border-color="#000000" -> 1 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - cplayer: Warning: property 'sub-text-align-x' was replaced with 'sub-align-x' and might be removed in the future. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-align-x="center" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-text-align-x="center" -> 1 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - cplayer: Warning: property 'sub-text-align-y' was replaced with 'sub-align-y' and might be removed in the future. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-align-y="bottom" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sub-text-align-y="bottom" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-sync="audio" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: hwdec="no" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: videotoolbox-format="nv12" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: deinterlace="no" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: display-fps=0.000000 -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-delay=0.000000 -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: cache=76800 -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-unscaled=false -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-aspect="-1" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: keepaspect=true -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: panscan=0.000000 -> 1 2018-03-29 12:27:26 [ DEBUG ] HelperLauncher.cpp @ 93 - Helper is running version: "2.7.0.806-608417d4" 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: vo="opengl-cb" -> 1 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: GL_VERSION='4.1 INTEL-10.30.12' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: Detected desktop OpenGL 4.1. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: GL_VENDOR='Intel Inc.' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: GL_RENDERER='Intel Iris Pro OpenGL Engine' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: GL_SHADING_LANGUAGE_VERSION='4.10' 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: Loaded extension GL_APPLE_rgb_422. 2018-03-29 12:27:26 [ WARN ] PlayerComponent.cpp @ 591 - opengl-cb: Function glMPGetNativeDisplay from extension GL_MP_MPGetNativeDisplay not found. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: Testing FBO format rgba16 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: Create FBO: 16x16 (16x16) 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: Using FBO format rgba16. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: No advanced processing required. Enabling dumb mode. 2018-03-29 12:27:26 [ DEBUG ] PlayerComponent.cpp @ 587 - opengl-cb: Loading hwdec driver 'videotoolbox' 2018-03-29 12:27:27 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-device="auto" -> 1 2018-03-29 12:27:27 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:166:onLoadingChanged: WebEngineLoadRequest starting: file:///Applications/Plex Media Player.app/Contents/Resources/web-client/desktop/index.html" 2018-03-29 12:27:27 [ DEBUG ] OSXUtils.mm @ 26 - Setting presentationOptions = 0 2018-03-29 12:27:28 [ DEBUG ] Log.cpp @ 29 - "qrc:/ui/webview.qml:170:onLoadingChanged: WebEngineLoadRequest success: file:///Applications/Plex Media Player.app/Contents/Resources/web-client/desktop/index.html" 2018-03-29 12:27:28 [ DEBUG ] SystemComponent.cpp @ 333 - "Web-client (3.39.5) fully inited." 2018-03-29 12:27:28 [ INFO ] JS: %cmyPlexAccessToken is not in BasicVolatileStorage 2018-03-29 12:27:28 [ WARN ] SettingsSection.cpp @ 82 - Looking for value: "connections" in section: "webclient" but it can't be found 2018-03-29 12:27:28 [ WARN ] SettingsSection.cpp @ 82 - Looking for value: "resumePlayQueue" in section: "webclient" but it can't be found 2018-03-29 12:27:28 [ WARN ] SettingsSection.cpp @ 82 - Looking for value: "skipSignIn" in section: "webclient" but it can't be found 2018-03-29 12:27:28 [ INFO ] JS: %c[UserManager] autoLogin false 2018-03-29 12:27:28 [ INFO ] JS: %c[Servers] Initializing... 2018-03-29 12:27:28 [ INFO ] JS: %c[Servers] Waiting for initial servers = [Loopback], plex.tv 2018-03-29 12:27:28 [ INFO ] JS: %c[Primary Server] Waiting to set the primary server 2018-03-29 12:27:28 [ INFO ] JS: %c[Primary Server] Waiting for the last primary server, 27ed87b0579d3efe85fec32f973082e741e0a343, to connect 2018-03-29 12:27:28 [ INFO ] JS: %c[Commands] Executing testServerConnection 2018-03-29 12:27:28 [ INFO ] JS: %c[Commands] Executing testServerConnection 2018-03-29 12:27:28 [ INFO ] JS: %c[Commands] Executing testServerConnection 2018-03-29 12:27:28 [ INFO ] JS: %c[Servers] Finished initialization 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Testing connection for mh-rpi-server at http://192.168.178.3:32400 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Testing connection for plex.tv at https://plex.tv/monitoring/health 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Testing connection for [Loopback] at http://127.0.0.1:32400 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] [Loopback] is unavailable at http://127.0.0.1:32400 (Status 0) 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] All connections to [Loopback] failed 2018-03-29 12:27:28 [ INFO ] JS: %c[Servers] Waiting for initial servers = plex.tv 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] mh-rpi-server connected at http://192.168.178.3:32400 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Active connection to mh-rpi-server is http://192.168.178.3:32400 2018-03-29 12:27:28 [ INFO ] JS: %c[Servers] Waiting for initial servers = plex.tv 2018-03-29 12:27:28 [ INFO ] JS: %c[MediaServerEventManager] Opening server event connection to mh-rpi-server at ws://192.168.178.3:32400/:/websockets/notifications?X-Plex-Token=REDACTED 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Adding possible loopback connection for mh-rpi-server 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Added connection http://127.0.0.1:32400 for mh-rpi-server 2018-03-29 12:27:28 [ INFO ] JS: %c[Commands] Executing testServerConnection 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Testing connection for mh-rpi-server at http://127.0.0.1:32400 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] mh-rpi-server is unavailable at http://127.0.0.1:32400 (Status 0) 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Retrying server events connection for mh-rpi-server in 1 seconds 2018-03-29 12:27:28 [ INFO ] JS: %c[Primary Server] mh-rpi-server is now the primary server 2018-03-29 12:27:28 [ INFO ] JS: %c[Commands] Executing checkForServerUpdates 2018-03-29 12:27:28 [ INFO ] JS: %c[MediaServerEventManager] Opened server event connection to mh-rpi-server at ws://192.168.178.3:32400/:/websockets/notifications?X-Plex-Token=REDACTED 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] plex.tv connected at https://plex.tv/monitoring/health 2018-03-29 12:27:28 [ INFO ] JS: %c[Connections] Active connection to plex.tv is https://plex.tv 2018-03-29 12:27:28 [ INFO ] JS: %c[User] Token promise succeeded, accessToken=REDACTED 2018-03-29 12:27:29 [ INFO ] JS: %c[User] userPromise succeeded 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] Populate any server chose mh-rpi-server where servers = [Loopback], mh-rpi-server, plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] Finding resources through plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] Waiting for additional resources from plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[CloudServerEventManager] Opening server event connection to plex.tv at wss://pubsub.plex.tv/sub/websockets/8986929/zksq08m419yg2skppp8bmx6r?X-Plex-Token=REDACTED 2018-03-29 12:27:29 [ INFO ] JS: %c[Companion] Registering player with plex.tv, clientID="zksq08m419yg2skppp8bmx6r" 2018-03-29 12:27:29 [ DEBUG ] SettingsComponent.cpp @ 140 - Updated possible values for: "updateChannel" to (QVariant(QVariantMap, QMap(("title", QVariant(QString, "Public"))("value", QVariant(int, 0)))), QVariant(QVariantMap, QMap(("title", QVariant(QString, "Beta"))("value", QVariant(int, 8))))) 2018-03-29 12:27:29 [ DEBUG ] PowerComponent.cpp @ 53 - Enabling OS screensaver 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] mh-rpi-server was found through plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] mh-pc was found through plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[Commands] Executing testServerConnection 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] Waiting for additional resources from plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] Waiting for additional resources from plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] Found 7 resources through plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[Servers] Found all servers = mh-rpi-server, plex.tv 2018-03-29 12:27:29 [ INFO ] JS: %c[CloudServerEventManager] Opened server event connection to plex.tv at wss://pubsub.plex.tv/sub/websockets/8986929/zksq08m419yg2skppp8bmx6r?X-Plex-Token=REDACTED 2018-03-29 12:27:29 [ INFO ] JS: %c[Companion] Registered player with plex.tv, clientID="zksq08m419yg2skppp8bmx6r" 2018-03-29 12:27:29 [ INFO ] JS: %c[Connections] Testing connection for mh-pc at http://192.168.178.2:32400 2018-03-29 12:27:30 [ INFO ] JS: %c[MDE] Augmented profile: { "directPlay": { "mp4": {}, "mp3": {}, "ogg": {}, "wav": {}, "flac": {}, "dts": {}, "ac3": {}, "aac": {}, "aiff": {}, "asf": {} }, "directStream": {} } 2018-03-29 12:27:30 [ INFO ] JS: %c[MDE] Starting analysis of 320 (mp3, mp3) 2018-03-29 12:27:30 [ INFO ] JS: %c[MDE] Analyzing direct play 2018-03-29 12:27:30 [ INFO ] JS: %c[MDE] Analyzing audio direct stream 2018-03-29 12:27:30 [ INFO ] JS: %c[MDE] Analyzing playability 2018-03-29 12:27:30 [ INFO ] JS: [MDE] Finished analysis of 320 (mp3, mp3) 2018-03-29 12:27:30 [ INFO ] JS: %ccanPlay: true 2018-03-29 12:27:30 [ INFO ] JS: %ccanDirectPlay: true 2018-03-29 12:27:30 [ INFO ] JS: %ccanDirectStreamAudio: true 2018-03-29 12:27:30 [ INFO ] JS: %cbitrate: 320 2018-03-29 12:27:30 [ INFO ] JS: console.groupEnd 2018-03-29 12:27:30 [ INFO ] JS: [PDE] Player decision 2018-03-29 12:27:30 [ INFO ] JS: %cPlayer: html 2018-03-29 12:27:30 [ INFO ] JS: %cProtocol: http 2018-03-29 12:27:30 [ INFO ] JS: %cDirect Play: true 2018-03-29 12:27:30 [ INFO ] JS: console.groupEnd 2018-03-29 12:27:30 [ INFO ] JS: [Transcoder] Music (decision) options 2018-03-29 12:27:30 [ INFO ] JS: %chasMDE: 1 2018-03-29 12:27:30 [ INFO ] JS: %cpath: /library/metadata/18522 2018-03-29 12:27:30 [ INFO ] JS: %cmediaIndex: 0 2018-03-29 12:27:30 [ INFO ] JS: %cpartIndex: 0 2018-03-29 12:27:30 [ INFO ] JS: %cdirectStream: 1 2018-03-29 12:27:30 [ INFO ] JS: %cX-Plex-Session-Identifier: zwa6bmuycitncrebrjiawz5m 2018-03-29 12:27:30 [ INFO ] JS: %csession: ezovvcba9csrt916jq6ywrk9 2018-03-29 12:27:30 [ INFO ] JS: %cprotocol: http 2018-03-29 12:27:30 [ INFO ] JS: %cdirectPlay: 1 2018-03-29 12:27:30 [ INFO ] JS: %coffset: 10 2018-03-29 12:27:30 [ INFO ] JS: %ccopyts: 0 2018-03-29 12:27:30 [ INFO ] JS: console.groupEnd 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: volume=90 -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: stop, flags=0, args=[] 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-sync="audio" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: hwdec="no" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: videotoolbox-format="nv12" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: deinterlace="no" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: display-fps=0.000000 -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 359 - Video framerate: 0 fps 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 363 - Not switching refresh-rate (disabled by settings). 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 609 - checking codecs 2018-03-29 12:27:30 [ INFO ] CodecsComponent.cpp @ 1260 - Using system audio decoders: true 2018-03-29 12:27:30 [ INFO ] CodecsComponent.cpp @ 1261 - Using system video decoders: false 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 611 - resuming loading 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: audio-delay=0.000000 -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: cache=76800 -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-unscaled=false -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: video-aspect="-1" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: keepaspect=true -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: panscan=0.000000 -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: loadfile, flags=0, args=[http://192.168.178.3:32400/library/parts/29905/1517863590/file.mp3?X-Plex-Session-Identifier=zwa6bmuycitncrebrjiawz5m&X-Plex-Client-Identifier=zksq08m419yg2skppp8bmx6r&X-Plex-Device-Screen-Resolution=1920x1155%2C1920x1200&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A8%7D%2Cac3%7Bbitrate%3A800000%26channels%3A8%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.806-608417d4&X-Plex-Device=OSX&X-Plex-Device-Name=mh-mbp&X-Plex-Model=10.13&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=de, append-play, ad=,aid=no,pause=yes,sid=no,start=+10,vd=,vid=no] 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: 'watch_later' -> '/Users/mh/Library/Application Support/Plex Media Player/watch_later' 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'ad' = '' (flags = 80) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'aid' = 'no' (flags = 80) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'pause' = 'yes' (flags = 80) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'sid' = 'no' (flags = 80) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'start' = '+10' (flags = 80) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'vd' = '' (flags = 80) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Setting option 'vid' = 'no' (flags = 80) 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - cplayer: Playing: http://192.168.178.3:32400/library/parts/29905/1517863590/file.mp3?X-Plex-Session-Identifier=zwa6bmuycitncrebrjiawz5m&X-Plex-Client-Identifier=zksq08m419yg2skppp8bmx6r&X-Plex-Device-Screen-Resolution=1920x1155%2C1920x1200&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A8%7D%2Cac3%7Bbitrate%3A800000%26channels%3A8%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.806-608417d4&X-Plex-Device=OSX&X-Plex-Device-Name=mh-mbp&X-Plex-Model=10.13&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=de 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Running hook: main/on_load 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: ad="mp3" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: vd="mp3" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: hook-ack, flags=0, args=[on_load] 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ffmpeg: Opening http://192.168.178.3:32400/library/parts/29905/1517863590/file.mp3?X-Plex-Session-Identifier=zwa6bmuycitncrebrjiawz5m&X-Plex-Client-Identifier=zksq08m419yg2skppp8bmx6r&X-Plex-Device-Screen-Resolution=1920x1155%2C1920x1200&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A8%7D%2Cac3%7Bbitrate%3A800000%26channels%3A8%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.806-608417d4&X-Plex-Device=OSX&X-Plex-Device-Name=mh-mbp&X-Plex-Model=10.13&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=de 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 451 - Entering state: paused 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ffmpeg: Could not set AVOption tls_verify='1' 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ffmpeg: Mime-type: 'audio/mpeg' 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ffmpeg: Stream opened successfully. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: no backbuffer needed 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: Cache size set to 9556 KiB (32 KiB backbuffer) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - demux: Trying demuxers for level=normal. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - lavf: Found 'mp3' at score=1 size=2048. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - lavf: Found 'mp3' at score=1 size=4096. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - lavf: Found 'mp3' at score=25 size=8192. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: Dropping cache at pos 9753068, cached range: 0-73728. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: Seeking underlying stream: 73728 -> 9753068 2018-03-29 12:27:30 [ INFO ] CodecsComponent.cpp @ 1260 - Using system audio decoders: true 2018-03-29 12:27:30 [ INFO ] CodecsComponent.cpp @ 1261 - Using system video decoders: false 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: EOF reached. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: Dropping cache at pos 0, cached range: 9753068-9753196. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: Seeking underlying stream: 9753196 -> 0 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ffmpeg/demuxer: mp3: Skipping 1035 bytes of junk at 0. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ffmpeg/demuxer: mp3: max_analyze_duration 500000 reached at 522449 microseconds st:0 2018-03-29 12:27:30 [ WARN ] PlayerComponent.cpp @ 591 - ffmpeg/demuxer: mp3: Estimating duration from bitrate, this may be inaccurate 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - lavf: avformat_find_stream_info() finished after 32768 bytes. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - demux: Detected file format: mp3 (libavformat) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: blocking for STREAM_CTRL 6 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Opening done: http://192.168.178.3:32400/library/parts/29905/1517863590/file.mp3?X-Plex-Session-Identifier=zwa6bmuycitncrebrjiawz5m&X-Plex-Client-Identifier=zksq08m419yg2skppp8bmx6r&X-Plex-Device-Screen-Resolution=1920x1155%2C1920x1200&X-Plex-Version=3.39.5&X-Plex-Client-Capabilities=protocols%3Dshoutcast%2Chttp-video%3BvideoDecoders%3Dh264%7Bprofile%3Ahigh%26resolution%3A2160%26level%3A52%7D%3BaudioDecoders%3Dmp3%2Caac%2Cdts%7Bbitrate%3A800000%26channels%3A8%7D%2Cac3%7Bbitrate%3A800000%26channels%3A8%7D&X-Plex-Product=Plex%20Media%20Player&X-Plex-Platform=Konvergo&X-Plex-Platform-Version=2.7.0.806-608417d4&X-Plex-Device=OSX&X-Plex-Device-Name=mh-mbp&X-Plex-Model=10.13&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Provider-Version=1.1&Accept-Language=de 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - global: config path: 'sub' -/-> '/Users/mh/Library/Application Support/Plex Media Player/sub' 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Running hook: main/on_preloaded 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: sid="no" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: aid="1" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: ad="mp3" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: vd="mp3" -> 1 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: hook-ack, flags=0, args=[on_preloaded] 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - cplayer: (+) Audio --aid=1 (mp3 2ch 44100Hz) 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - display-tags: File tags: 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - display-tags: Artist: Aesop Rock 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - display-tags: Album: None Shall Pass 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - display-tags: Genre: Blues 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - display-tags: Title: None Shall Pass 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - display-tags: Track: 2 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ad: Codec list: 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ad: mp3 - MP3 (MPEG audio layer 3) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ad: mp3 - MP3 (MPEG audio layer 3) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ad: Opening audio decoder mp3 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ad: Requesting 1 threads for decoding. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ad: Selected audio codec: mp3 (MP3 (MPEG audio layer 3)) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Starting playback... 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - lavf: queuing seek to 10.000000 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: hr-seek, skipping to 10.000000 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - lavf: execute seek (to 10.000000 flags 40) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - lavf: seek done 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Audio filter chain: 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [in] 44100Hz stereo 2ch s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [out] 44100Hz stereo 2ch s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [ao] 44100Hz stereo 2ch s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao: Trying audio driver 'coreaudio' 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: requested format: 44100 Hz, stereo channels, s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: selected audio output device: DisplayPort (58) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: input channel layout: 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: layout: tag: <0>, bitmap: <0>, descriptions <2> 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: - description 0: label <1, 0>, flags: <0>, coords: <0.000000, 0.000000, 0.000000> 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: - description 1: label <2, 1>, flags: <0>, coords: <0.000000, 0.000000, 0.000000> 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: mp chmap: stereo 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: input channel layout: 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: layout: tag: <0>, bitmap: <0>, descriptions <2> 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: - description 0: label <1, 0>, flags: <0>, coords: <0.000000, 0.000000, 0.000000> 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: - description 1: label <2, 1>, flags: <0>, coords: <0.000000, 0.000000, 0.000000> 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: mp chmap: stereo 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: Channel layouts: 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: - stereo 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: - stereo 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: - mono 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: result: stereo 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: Latency property cntl: 5 frames 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: Latency property zisf: 512 frames 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: Latency property tfas: 0 frames 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: audiounit latency [us]: 385 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: device latency [us]: 11723 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - ao/coreaudio: using soft-buffer of 8820 samples. 2018-03-29 12:27:30 [ INFO ] PlayerComponent.cpp @ 589 - cplayer: AO: [coreaudio] 44100Hz stereo 2ch s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: AO: Description: CoreAudio AudioUnit 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Inserting volume filter. 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Adding filter volume 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Setting option 'warn' = 'no' (flags = 0) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Adding filter lavrresample 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Setting option 'normalize' = 'yes' (flags = 0) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Setting option 'o' = 'surround_mix_level=1' (flags = 0) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Adding filter lavrresample 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Setting option 'normalize' = 'yes' (flags = 0) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Setting option 'o' = 'surround_mix_level=1' (flags = 0) 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: Audio filter chain: 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [in] 44100Hz stereo 2ch s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [lavrresample] 44100Hz stereo 2ch floatp [a] 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [volume] "softvol" 44100Hz stereo 2ch floatp 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [lavrresample] 44100Hz stereo 2ch s16p [a] 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [out] 44100Hz stereo 2ch s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - af: [ao] 44100Hz stereo 2ch s16p 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - volume: volume gain: 0.729000 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: starting audio playback 2018-03-29 12:27:30 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: playback restart complete 2018-03-29 12:27:31 [ DEBUG ] PlayerComponent.cpp @ 587 - cache: EOF reached. 2018-03-29 12:27:33 [ INFO ] JS: %c[MediaElement] Creating new media element. type: audio 2018-03-29 12:27:33 [ INFO ] JS: %c[MediaElement] Releasing media element to pool. type: audio 2018-03-29 12:27:33 [ INFO ] JS: Uncaught (in promise) NotSupportedError: Failed to load because no supported source was found. 2018-03-29 12:27:33 [ DEBUG ] PowerComponent.cpp @ 58 - Disabling OS screensaver 2018-03-29 12:27:33 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: set, flags=0, args=[pause, no] 2018-03-29 12:27:33 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: pause=no -> 1 2018-03-29 12:27:33 [ INFO ] PlayerComponent.cpp @ 455 - Entering state: playing 2018-03-29 12:27:33 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1 } 2018-03-29 12:27:38 [ DEBUG ] SystemComponent.cpp @ 152 - "System Information : build(macosx)-arch(x86_64).dist(macosx).version(2.7.0.806-608417d4).productid(3)" 2018-03-29 12:27:38 [ DEBUG ] UpdaterComponent.cpp @ 86 - Checking for updates at: "https://plex.tv/updater/products/3/check.xml?version=2.7.0.806-608417d4&build=macosx-x86_64&distribution=macosx&channel=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx" 2018-03-29 12:27:39 [ DEBUG ] UpdaterComponent.cpp @ 354 - No updates found! 2018-03-29 12:27:39 [ INFO ] JS: %c[Connections] mh-pc is unavailable at http://192.168.178.2:32400 (Status 0) 2018-03-29 12:27:39 [ INFO ] JS: %c[Connections] All connections to mh-pc failed 2018-03-29 12:27:39 [ INFO ] JS: %c[Servers] Found all servers = mh-rpi-server, plex.tv 2018-03-29 12:27:40 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 16000, "duration": 243000 } 2018-03-29 12:27:50 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 26000, "duration": 243000 } 2018-03-29 12:27:51 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 27000, "duration": 243000 } 2018-03-29 12:28:01 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 37000, "duration": 243000 } 2018-03-29 12:28:21 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 57000, "duration": 243000 } 2018-03-29 12:28:30 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 67000, "duration": 243000 } 2018-03-29 12:28:31 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 67000, "duration": 243000 } 2018-03-29 12:28:40 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 77000, "duration": 243000 } 2018-03-29 12:28:41 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "playing", "hasMDE": 1, "time": 77000, "duration": 243000 } 2018-03-29 12:28:46 [ DEBUG ] PowerComponent.cpp @ 53 - Enabling OS screensaver 2018-03-29 12:28:46 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Run command: set, flags=0, args=[pause, yes] 2018-03-29 12:28:46 [ DEBUG ] PlayerComponent.cpp @ 587 - cplayer: Set property: pause=yes -> 1 2018-03-29 12:28:46 [ INFO ] PlayerComponent.cpp @ 451 - Entering state: paused 2018-03-29 12:28:46 [ INFO ] JS: %c[Player] Timeline, { "ratingKey": "18522", "key": "/library/metadata/18522", "playQueueItemID": "3664", "state": "paused", "hasMDE": 1 } 2018-03-29 12:28:48 [ DEBUG ] KonvergoWindow.cpp @ 198 - Window state when saving geometry: QWindow::Visibility(Windowed) 2018-03-29 12:28:48 [ DEBUG ] KonvergoWindow.cpp @ 209 - Saving window geometry: QRect(-1920,107 1920x1155) 2018-03-29 12:28:48 [ DEBUG ] KonvergoWindow.cpp @ 513 - QWindow visibility set to QWindow::Visibility(Hidden) 2018-03-29 12:28:48 [ DEBUG ] KonvergoWindow.cpp @ 643 - resize event: QSize(1920, 1155) 2018-03-29 12:28:48 [ DEBUG ] KonvergoWindow.cpp @ 198 - Window state when saving geometry: QWindow::Visibility(Hidden) 2018-03-29 12:28:48 [ DEBUG ] KonvergoWindow.cpp @ 209 - Saving window geometry: QRect(-1920,107 1920x1155) ```
LongChair commented 6 years ago

Hi,

Do you guys have the normalize volume option ticked ? Could you check if you have the same behavior without it ?

katafractari commented 6 years ago

@LongChair Where is the normalization option?

kinglouie commented 6 years ago

If you mean the multichannel volume setting, I disabled that and CPU is still up. I found out that CPU load drops to a normal level if the PMP window is not visible ( e.g. when changing the space), so I believe it should have something to do with the webview rendering.

katafractari commented 6 years ago

My multichannel audio boost is set to None. My CPU usage is high (30-40%) even if the app is not visible. I'm using Gnome 3.28.

kinglouie commented 6 years ago

I uploaded an instruments profile of PMP where you can see how much CPU spikes when the app is in foreground

Download Profile

bildschirmfoto 2018-03-29 um 16 50 50
katafractari commented 6 years ago

I tried runing PMP in Cinnamon desktop (from Linux Mint) because Gnome 3 doesn't really "minimize" apps. CPU usage is even higher here (40-50%) and it doesn't matter if the window is minimized or maximized.

katafractari commented 6 years ago

CPU usage is normal only in the case of playing via plex.tv in a Chrome tab that does not have focus. By "normal" I mean almost the same CPU utilization as when playing an MP3 directly in Chrome by opening a file. So apparently it has someting to do with graphics rendering.

mseeley commented 6 years ago

:wave: hi @katafractari.

That CPU usage is ugly. I confirmed playback of a 320Kbps MP3 on macOS using our 2.7.0 release shows high CPU usage; I see ~20% CPU. I then hacked the UI's player position event listener to cause no side effects. CPU usage dropped to ~2.5%. I filed an issue to our private UI project to address this; we'll update here when that issue is addressed.

An interesting test would be:

The main difference is the abstraction for different media players. We see high CPU in PMP as the player is notifying of player updates at an extreme rate. We should see lowest CPU in Chrome playing the MP3 alone and somewhat higher in Chrome with Plex playing the MP3. The media abstraction used in Chrome notifies position updates more slowly than PMP.

ipleten commented 6 years ago

I have the same issue under Chrome image

Load avg increases to 5 on 4 cores CPU. It's absolutely annoying.

LongChair commented 6 years ago

@ipleten : We have been able to reproduce the issue and are working on a fix.

LongChair commented 6 years ago

@markus101 : can we link the according internal issue for this one in order to track progress ? :)

markus101 commented 6 years ago

Thanks for the reminder, the internal issue is fixed and closed. Closing this as well.

kinglouie commented 6 years ago

@markus101 when will this fix be released? I just tested the latest macOS version and have still 75% CPU usage when the window is visible.

EDIT: Just checked the last release notes, it seems this should have been fixed in 2.10 already but for me the issue is still there on 2.11.

markus101 commented 6 years ago

It was released in 2.10.0: Fixed high CPU usage during music playback

https://github.com/plexinc/plex-media-player/blob/master/release-notes/2.10.0.txt

I'm seeing between 0.5% and 3% CPU usage on my system.

kinglouie commented 6 years ago
bildschirmfoto 2018-05-30 um 17 25 42

I just did a new istruments profile on mac high sierra that can be downloaded here: http://matthias-hollerbach.de/plex-2.11.trace.zip

ipleten commented 6 years ago

It was fine but starts affecting me again.

markus101 commented 6 years ago

@LongChair are you able to check if this is related to the QT update/something on the host side or a regression on the web side? Previously this affected windows and mac OS, so it seems more host related.

kinglouie commented 6 years ago

I just noticed that plex media player is ramping up the CPU when it shows the scanning notices. I think this boils down to the same issue with QT since it is the QT process that gets high CPU when this is rendered

bildschirmfoto 2018-06-07 um 09 30 48
ipleten commented 6 years ago

playing music: Minimized - cpu usage 5-6% Maximized - 50-60% Not playing music: ~1-4%

kinglouie commented 6 years ago

@ipleten do you happen to have a 4k display? I have Similar Issues with Firefox on Mac with a 4k display. Maybe the QTWebview doesn't perform well on high res displays.

mseeley commented 6 years ago

I'll look into what's up.

ipleten commented 6 years ago

Sorry for late answer. No I have dual FHD monitors on Ubuntu 16.04 em... I've just realized that I'm talking about Plex Web Player.

mseeley commented 6 years ago

Our CPU usage is looking fine playing music, particularly in the TV layout. The TV layout relaxes its work on each playback position update.

Full-screen maximized on 15" hi-dpi display

Desktop

screen shot 2018-08-20 at 4 45 13 pm

TV

screen shot 2018-08-20 at 4 48 04 pm

Windowed (1280x711) on a 2560x1440 display

Desktop

screen shot 2018-08-20 at 4 40 10 pm

TV

screen shot 2018-08-20 at 4 41 27 pm

We'll look to improve Desktop layout performance in the future. For now I think we're safe to re-close this.