pb.stoleyour.com

Untitled

By Capacious Dormouse, 11 Years ago, written in Plain Text, viewed 1'259 times.
URL http://pb.stoleyour.com/view/d34a3f94 Embed
Download Paste or View Raw — Expand paste to full width of browser
  1. Mar 18, 2013 18:29:47:946 [4852] INFO - LANGID = 0x409
  2. Mar 18, 2013 18:29:47:946 [4852] DEBUG - LoadMUILibraryW(0) returned 0x740A0002
  3. Mar 18, 2013 18:29:47:946 [4852] INFO - Windows version 6.1 (Build 7601)
  4. Mar 18, 2013 18:29:47:946 [4852] INFO - 4 2128 MHz processor(s): Architecture=0, Level=6, Revision=9474
  5. Mar 18, 2013 18:29:47:946 [4852] INFO - PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 37 Stepping 2, GenuineIntel
  6. Mar 18, 2013 18:29:47:946 [4852] INFO - Plex Media Server v0.9.7.17-1f0b170
  7. Mar 18, 2013 18:29:47:946 [4852] DEBUG - Opening 10 database sessions to library, SQLite 3.7.15.2, threadsafe=1
  8. Mar 18, 2013 18:29:47:977 [4852] DEBUG - Running migrations.
  9. Mar 18, 2013 18:29:47:977 [4852] DEBUG - Listening on port 32400 with queue of 2147483647 connections.
  10. Mar 18, 2013 18:29:48:071 [4852] DEBUG - Adding directory for site configurations: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins/SiteConfigurations.bundle/Contents/Resources
  11. Mar 18, 2013 18:29:48:071 [4852] DEBUG - The path changed: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins/SiteConfigurations.bundle/Contents/Resources.
  12. Mar 18, 2013 18:29:48:071 [4852] DEBUG - This corresponds to site configuration map: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins/SiteConfigurations.bundle/Contents/Resources (directory: 1)
  13. Mar 18, 2013 18:29:48:087 [4852] DEBUG - Adding directory for site configurations: C:\Users\Administrator\AppData\Local\Plex Media Server\Site Configurations
  14. Mar 18, 2013 18:29:48:087 [4852] DEBUG - The path changed: C:\Users\Administrator\AppData\Local\Plex Media Server\Site Configurations.
  15. Mar 18, 2013 18:29:48:087 [4852] DEBUG - This corresponds to site configuration map: C:\Users\Administrator\AppData\Local\Plex Media Server\Site Configurations (directory: 1)
  16. Mar 18, 2013 18:29:48:087 [4052] DEBUG - File C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins/SiteConfigurations.bundle/Contents/Resources changed: -1 => -1.
  17. Mar 18, 2013 18:29:48:087 [4052] DEBUG - File C:\Users\Administrator\AppData\Local\Plex Media Server\Site Configurations changed: -1 => -1.
  18. Mar 18, 2013 18:29:48:087 [4052] DEBUG - The path changed: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins/SiteConfigurations.bundle/Contents/Resources.
  19. Mar 18, 2013 18:29:48:087 [4052] DEBUG - This corresponds to site configuration map: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins/SiteConfigurations.bundle/Contents/Resources (directory: 1)
  20. Mar 18, 2013 18:29:48:087 [4052] DEBUG - The path changed: C:\Users\Administrator\AppData\Local\Plex Media Server\Site Configurations.
  21. Mar 18, 2013 18:29:48:087 [4052] DEBUG - This corresponds to site configuration map: C:\Users\Administrator\AppData\Local\Plex Media Server\Site Configurations (directory: 1)
  22. Mar 18, 2013 18:29:48:414 [3196] DEBUG - WebKit supports Flash content
  23. Mar 18, 2013 18:29:48:414 [3196] DEBUG - WebKit supports Silverlight content
  24. Mar 18, 2013 18:29:48:414 [3196] INFO - Successfully initialized WebKit
  25. Mar 18, 2013 18:29:48:414 [4852] DEBUG - Setting up scheduled updates
  26. Mar 18, 2013 18:29:48:414 [4852] INFO - LibraryUpdateManager path watching is disabled
  27. Mar 18, 2013 18:29:48:414 [4180] DEBUG - Starting scheduled updates, every 21600 seconds
  28. Mar 18, 2013 18:29:48:430 [4852] DEBUG - Checking for blacklisted version for Framework.bundle (version 42865ab)
  29. Mar 18, 2013 18:29:48:430 [4852] DEBUG - Checking for blacklisted version for System.bundle (version 92ccaa6)
  30. Mar 18, 2013 18:29:48:430 [5228] DEBUG - File C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins changed: -1 => -1.
  31. Mar 18, 2013 18:29:48:430 [5228] DEBUG - Scanning for plug-ins in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins
  32. Mar 18, 2013 18:29:48:430 [4324] DEBUG - Loading CAs from 'C:\Program Files (x86)\Plex\Plex Media Server\Resources\cacert.pem'
  33. Mar 18, 2013 18:29:48:430 [5228] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\System.bundle.
  34. Mar 18, 2013 18:29:48:430 [5228] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
  35. Mar 18, 2013 18:29:48:446 [5228] DEBUG - Starting file watcher for com.plexapp.system
  36. Mar 18, 2013 18:29:48:449 [5228] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files (x86)\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle\Contents\Resources\Versions\2\Python/bootstrap.py" "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\System.bundle"
  37. Mar 18, 2013 18:29:48:467 [5228] DEBUG - Spawned plug-in com.plexapp.system with PID 1428
  38. Mar 18, 2013 18:29:48:467 [5228] DEBUG - [com.plexapp.system] Sent command: GET /:/prefixes
  39.  
  40. Mar 18, 2013 18:29:52:502 [5228] DEBUG - Reading 323 bytes in the body, code is 200
  41. Mar 18, 2013 18:29:52:502 [5228] DEBUG - [com.plexapp.system] Plug-in running on port 58479.
  42. Mar 18, 2013 18:29:52:502 [5228] DEBUG -  * Plug-in handles prefix: /system
  43. Mar 18, 2013 18:29:52:502 [5228] DEBUG - Read configuration for [com.plexapp.system], had 1 prefixes
  44. Mar 18, 2013 18:29:52:611 [5228] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
  45. Mar 18, 2013 18:29:52:611 [5228] DEBUG - Updating the list of agents known by the system.
  46. Mar 18, 2013 18:29:52:611 [5228] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
  47. Mar 18, 2013 18:29:52:611 [5228] DEBUG - HTTP requesting to: http://127.0.0.1:58479/system/agents
  48. Mar 18, 2013 18:29:52:705 [4852] WARN - iTunes library not found at expected location: C:\Users\Administrator\Music\iTunes\iTunes Music Library.xml (The system cannot find the path specified)
  49. Mar 18, 2013 18:29:52:705 [4852] DEBUG - Loading iTunes COM class to get library XML file path
  50. Mar 18, 2013 18:29:52:705 [4852] ERROR - Failed to create iTunes COM instance (-2147221164)
  51. Mar 18, 2013 18:29:52:705 [4852] DEBUG - Found 0 library files
  52. Mar 18, 2013 18:29:52:705 [4852] WARN - Failed to open iTunes registry key at CLSID\{DC0C2640-1415-4644-875C-6F4D769839BA}\LocalServer32
  53. Mar 18, 2013 18:29:52:705 [4852] DEBUG - Image transcode cache directory: C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder
  54. Mar 18, 2013 18:29:52:720 [4852] DEBUG - Bonjour: DNSServiceBrowse succeeded
  55. Mar 18, 2013 18:29:52:752 [4852] DEBUG - Bonjour: DNSServiceBrowse succeeded
  56. Mar 18, 2013 18:29:52:752 [4852] INFO - Bonjour: registering PMS service
  57. Mar 18, 2013 18:29:52:752 [4852] INFO - Bonjour: serviceType = _plexmediasvr._tcp, friendlyName = YGGDRASIL, domain = NULL, hostname = NULL, servicePort = 32400
  58. Mar 18, 2013 18:29:52:752 [4852] INFO - Bonjour: machineIdentifier = 6e186c42359e78e9c87c7998009a151e91cc6c8b, version = 0.9.7.17-1f0b170, playersModified = 1363645792.0
  59. Mar 18, 2013 18:29:52:752 [3656] DEBUG - Bonjour: DNSServiceBrowse_Callback: sdRef = 0x03EC0EF8, flags = 2, interfaceIndex = 15, code = 0
  60. Mar 18, 2013 18:29:52:752 [3656] DEBUG - Bonjour: DNSServiceBrowse_Callback: serviceName = MAPODOFU, regtype = _plexmediasvr._tcp., replyDomain = local.
  61. Mar 18, 2013 18:29:52:752 [3656] DEBUG - Bonjour: DNSServiceBrowse_Callback: calling DNSServiceResolve on MAPODOFU
  62. Mar 18, 2013 18:29:52:752 [4852] DEBUG - Bonjour: DNSServiceRegister succeeded
  63. Mar 18, 2013 18:29:52:752 [3656] DEBUG - Bonjour: DNSServiceResolve succeeded, adding reference 0x035680B0, new ResolveContext instance 0x28A7BB02
  64. Mar 18, 2013 18:29:52:752 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: sdResolveRef = 0x03ECC8B0, flags = 0, interfaceIndex = 15, errorCode = 0
  65. Mar 18, 2013 18:29:52:752 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: fullname = MAPODOFU._plexmediasvr._tcp.local., hosttarget = MAPODOFU.local., tcpport = 36990, txtLen = 113
  66. Mar 18, 2013 18:29:52:752 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: resolved TXT record for MAPODOFU._plexmediasvr._tcp.local.: host = MAPODOFU.local., machineIdentifier = 57910a53873f40dbb5d840c429b206d18b8851ea, version = 0.9.7.17-1f0b170
  67. Mar 18, 2013 18:29:52:752 [4852] DEBUG - NAT: Starting Mapping Thread.
  68. Mar 18, 2013 18:29:52:752 [1596] DEBUG - HTTP requesting to: https://my.plexapp.com/users/account.xml?auth_token=uwYZxRhtAoC5oyYRpaih
  69. Mar 18, 2013 18:29:52:752 [4852] DEBUG - NAT: Using manually specified port of 32400.
  70. Mar 18, 2013 18:29:52:752 [4852] DEBUG - NAT: Creating NAT puncher thread 03561E68
  71. Mar 18, 2013 18:29:52:752 [4852] DEBUG - Creating NetworkServices singleton.
  72. Mar 18, 2013 18:29:52:752 [4852] DEBUG - NetworkServices: Initializing...
  73. Mar 18, 2013 18:29:52:752 [4852] DEBUG - NetworkInterface: Notified of network changed (force=0)
  74. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Updating mapping in puncher thread 03561E68
  75. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Creating puncher.
  76. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Switching to NAT-PMP strategy.
  77. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Creating NATPmPPuncher.
  78. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Initializing NAT-PMP, success=1
  79. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Finding public address from NAT-PMP.
  80. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Finished NAT-PMP request, result was: NATPMP_ERR_RECVFROM
  81. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Found public address (success=0): 0.0.0.0
  82. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Finding public address failed the first way, switching.
  83. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Switching to UPnP strategy.
  84. Mar 18, 2013 18:29:52:752 [5916] DEBUG - NAT: Creating NATUPnPPuncher.
  85. Mar 18, 2013 18:29:52:767 [4852] DEBUG - Network interfaces:
  86. Mar 18, 2013 18:29:52:767 [4852] DEBUG -  * 1 {065F0C42-703A-11DE-9954-806E6F6E6963} (127.0.0.1) (loopback: 1)
  87. Mar 18, 2013 18:29:52:767 [4852] DEBUG -  * 15 {BA974BA7-3123-4855-B4EA-BBF61337B6BE} (192.168.1.100) (loopback: 0)
  88. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035AF700: Creating new Network Service and registering for notifications.
  89. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035AF700: NetworkService got notification of changed network (first change: 1)
  90. Mar 18, 2013 18:29:52:767 [4852] DEBUG - NetworkService: Quick dispatch of network change.
  91. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035B1740: Creating new Network Service and registering for notifications.
  92. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035B1740: NetworkService got notification of changed network (first change: 1)
  93. Mar 18, 2013 18:29:52:767 [4852] DEBUG - NetworkService: Quick dispatch of network change.
  94. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035B3780: Creating new Network Service and registering for notifications.
  95. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035B3780: NetworkService got notification of changed network (first change: 1)
  96. Mar 18, 2013 18:29:52:767 [4852] DEBUG - NetworkService: Quick dispatch of network change.
  97. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035B5830: Creating new Network Service and registering for notifications.
  98. Mar 18, 2013 18:29:52:767 [4852] DEBUG - 035B5830: NetworkService got notification of changed network (first change: 1)
  99. Mar 18, 2013 18:29:52:767 [4852] DEBUG - NetworkService: Quick dispatch of network change.
  100. Mar 18, 2013 18:29:52:767 [2528] DEBUG - Network change for advertiser.
  101. Mar 18, 2013 18:29:52:767 [4852] DEBUG - Starting HTTP server.
  102. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
  103. Mar 18, 2013 18:29:52:767 [4852] DEBUG - Running server...
  104. Mar 18, 2013 18:29:52:767 [4852] DEBUG - Waiting for server condition to be signaled.
  105. Mar 18, 2013 18:29:52:767 [3912] DEBUG - DLNA server starting.
  106. Mar 18, 2013 18:29:52:767 [3912] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\PlexDlnaServer.exe
  107. Mar 18, 2013 18:29:52:767 [2528] DEBUG - Network change for advertiser.
  108. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
  109. Mar 18, 2013 18:29:52:767 [2528] DEBUG - Network change for browser (polled=0), closing 0 browse sockets.
  110. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
  111. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Browsing on interface 127.0.0.1.
  112. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Setting up multicast listener on 127.0.0.1:0 (outbound: 1)
  113. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Browsing on interface 192.168.1.100.
  114. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Setting up multicast listener on 192.168.1.100:0 (outbound: 1)
  115. Mar 18, 2013 18:29:52:767 [3656] DEBUG - Bonjour: MonitorTxtRecord - DNSServiceConstructFullName succeeded: MAPODOFU._plexmediasvr._tcp.local.
  116. Mar 18, 2013 18:29:52:767 [2528] DEBUG - Network change for browser (polled=1), closing 0 browse sockets.
  117. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Browsing on interface 127.0.0.1.
  118. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Setting up multicast listener on 127.0.0.1:0 (outbound: 1)
  119. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Browsing on interface 192.168.1.100.
  120. Mar 18, 2013 18:29:52:767 [2528] DEBUG - NetworkService: Setting up multicast listener on 192.168.1.100:0 (outbound: 1)
  121. Mar 18, 2013 18:29:52:767 [0932] DEBUG - Request: GET /library/sections [192.168.1.65:64268] (5 live)
  122. Mar 18, 2013 18:29:52:767 [3200] DEBUG - Set up a thread pool for HTTP server with 2 threads.
  123. Mar 18, 2013 18:29:52:767 [3200] DEBUG - Running database fix-ups.
  124. Mar 18, 2013 18:29:52:767 [3656] DEBUG - Bonjour: DNSServiceQueryRecord succeeded, QueryRecordContext = 0x034F11D8
  125. Mar 18, 2013 18:29:52:767 [3656] INFO - Bonjour: adding host - name = MAPODOFU, hostname - MAPODOFU.local., machineIdentifier = 57910a53873f40dbb5d840c429b206d18b8851ea
  126. Mar 18, 2013 18:29:52:767 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: removing reference 0x03ECC8B0, deleting ResolveContext instance 0x035680B0
  127. Mar 18, 2013 18:29:52:767 [3656] DEBUG - Bonjour: DNSServiceQueryRecord_Callback succeeded, QueryRecordContext = 0x034F11D8
  128. Mar 18, 2013 18:29:52:767 [3656] INFO - Bonjour: TXT record added for MAPODOFU._plexmediasvr._tcp.local.
  129. Mar 18, 2013 18:29:52:767 [5104] DEBUG - Request: GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ [127.0.0.1:58475] (5 live)
  130. Mar 18, 2013 18:29:52:783 [5104] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
  131. Mar 18, 2013 18:29:52:783 [5104] DEBUG - HTTP requesting to: http://127.0.0.1:58479/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
  132. Mar 18, 2013 18:29:52:783 [5760] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=MAPODOFU.local.
  133. Mar 18, 2013 18:29:52:783 [5760] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=MAPODOFU.local.
  134. Mar 18, 2013 18:29:52:783 [0932] DEBUG - It took 0.016000 sec to serialize a list with 5 elements.
  135. Mar 18, 2013 18:29:52:783 [5760] DEBUG - HTTP requesting to: http://127.0.0.1:58479/system/notify/serverUpdated?host=MAPODOFU.local.
  136. Mar 18, 2013 18:29:52:783 [5576] DEBUG - Request: GET / [127.0.0.1:58477] (5 live)
  137. Mar 18, 2013 18:29:52:783 [2528] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.1.100
  138. Mar 18, 2013 18:29:52:783 [2528] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.1.100
  139. Mar 18, 2013 18:29:52:783 [2528] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.1.100
  140. Mar 18, 2013 18:29:52:783 [5576] DEBUG - It took 0.000000 sec to serialize a list with 7 elements.
  141. Mar 18, 2013 18:29:52:783 [2528] DEBUG - HTTP requesting to: http://127.0.0.1:58479/system/notify/serverUpdated?host=192.168.1.100
  142. Mar 18, 2013 18:29:52:783 [5224] DEBUG - Request: GET /servers [127.0.0.1:58478] (5 live)
  143. Mar 18, 2013 18:29:52:783 [5224] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.
  144. Mar 18, 2013 18:29:52:783 [3164] DEBUG - Request: GET / [127.0.0.1:58476] (5 live)
  145. Mar 18, 2013 18:29:52:783 [3164] DEBUG - It took 0.000000 sec to serialize a list with 7 elements.
  146. Mar 18, 2013 18:29:52:783 [0932] DEBUG - Request: GET /channels/all [192.168.1.65:64271] (5 live)
  147. Mar 18, 2013 18:29:52:783 [0932] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  148. Mar 18, 2013 18:29:52:861 [0932] DEBUG - Request: GET / [127.0.0.1:58496] (5 live)
  149. Mar 18, 2013 18:29:52:876 [0932] DEBUG - It took 0.000000 sec to serialize a list with 7 elements.
  150. Mar 18, 2013 18:29:52:954 [5224] DEBUG - Request: GET /library/sections [192.168.1.100:58497] (5 live)
  151. Mar 18, 2013 18:29:52:954 [3164] DEBUG - Request: GET /servers [127.0.0.1:58498] (5 live)
  152. Mar 18, 2013 18:29:52:954 [0932] DEBUG - Request: GET /servers [127.0.0.1:58499] (5 live)
  153. Mar 18, 2013 18:29:52:970 [3164] DEBUG - It took 0.016000 sec to serialize a list with 2 elements.
  154. Mar 18, 2013 18:29:52:986 [0932] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.
  155. Mar 18, 2013 18:29:53:048 [5576] DEBUG - Request: GET /library/sections [192.168.1.100:58501] (5 live)
  156. Mar 18, 2013 18:29:53:079 [3164] DEBUG - Request: GET /library/sections [192.168.1.100:58504] (4 live)
  157. Mar 18, 2013 18:29:53:126 [5224] DEBUG - It took 0.125000 sec to serialize a list with 5 elements.
  158. Mar 18, 2013 18:29:53:126 [5576] DEBUG - It took 0.031000 sec to serialize a list with 5 elements.
  159. Mar 18, 2013 18:29:53:157 [3164] DEBUG - It took 0.047000 sec to serialize a list with 5 elements.
  160. Mar 18, 2013 18:29:53:157 [0932] DEBUG - Request: GET /channels/all [192.168.1.100:58505] (5 live)
  161. Mar 18, 2013 18:29:53:157 [0932] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  162. Mar 18, 2013 18:29:53:188 [3164] DEBUG - Request: GET /channels/all [192.168.1.100:58506] (5 live)
  163. Mar 18, 2013 18:29:53:188 [3164] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  164. Mar 18, 2013 18:29:53:220 [0932] DEBUG - Request: GET /channels/all [192.168.1.100:58507] (5 live)
  165. Mar 18, 2013 18:29:53:220 [0932] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  166. Mar 18, 2013 18:29:53:251 [2528] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
  167. Mar 18, 2013 18:29:53:251 [2528] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.1.111
  168. Mar 18, 2013 18:29:53:251 [2528] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.1.65
  169. Mar 18, 2013 18:29:53:251 [2528] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.1.65
  170. Mar 18, 2013 18:29:53:251 [2528] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.1.65
  171. Mar 18, 2013 18:29:53:251 [2528] DEBUG - HTTP requesting to: http://127.0.0.1:58479/system/notify/serverUpdated?host=192.168.1.65
  172. Mar 18, 2013 18:29:53:266 [5760] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
  173. Mar 18, 2013 18:29:53:313 [5576] DEBUG - Request: GET /servers [127.0.0.1:58511] (5 live)
  174. Mar 18, 2013 18:29:53:313 [5576] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.
  175. Mar 18, 2013 18:29:53:438 [0932] DEBUG - Request: GET /library/sections [192.168.1.100:58517] (5 live)
  176. Mar 18, 2013 18:29:53:469 [0932] DEBUG - It took 0.015000 sec to serialize a list with 5 elements.
  177. Mar 18, 2013 18:29:53:516 [5576] DEBUG - Request: GET /channels/all [192.168.1.100:58519] (5 live)
  178. Mar 18, 2013 18:29:53:516 [5576] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  179. Mar 18, 2013 18:29:53:547 [2528] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
  180. Mar 18, 2013 18:29:53:641 [3656] DEBUG - Bonjour: DNSServiceBrowse_Callback: sdRef = 0x03EC0EF8, flags = 2, interfaceIndex = 15, code = 0
  181. Mar 18, 2013 18:29:53:641 [3656] DEBUG - Bonjour: DNSServiceBrowse_Callback: serviceName = YGGDRASIL, regtype = _plexmediasvr._tcp., replyDomain = local.
  182. Mar 18, 2013 18:29:53:641 [3656] DEBUG - Bonjour: DNSServiceBrowse_Callback: calling DNSServiceResolve on YGGDRASIL
  183. Mar 18, 2013 18:29:53:656 [3656] DEBUG - Bonjour: DNSServiceResolve succeeded, adding reference 0x08046E88, new ResolveContext instance 0x28A7BB02
  184. Mar 18, 2013 18:29:54:499 [5228] DEBUG - [com.plexapp.system] HTTP reply status 200, with 3220 bytes of content.
  185. Mar 18, 2013 18:29:54:499 [5228] DEBUG - The system knows about 13 agents.
  186. Mar 18, 2013 18:29:54:499 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Cine21.bundle.
  187. Mar 18, 2013 18:29:54:499 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle.
  188. Mar 18, 2013 18:29:54:499 [5228] DEBUG - Waiting for all 24 plug-ins to finish starting.
  189. Mar 18, 2013 18:29:54:499 [5260] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\HTbackdrops.bundle.
  190. Mar 18, 2013 18:29:54:499 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\IMDB.bundle.
  191. Mar 18, 2013 18:29:54:499 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.cine21] yet.
  192. Mar 18, 2013 18:29:54:499 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\LastFM.bundle.
  193. Mar 18, 2013 18:29:54:499 [5260] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
  194. Mar 18, 2013 18:29:54:499 [5260] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\LocalMedia.bundle.
  195. Mar 18, 2013 18:29:54:499 [3412] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
  196. Mar 18, 2013 18:29:54:499 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Media-Flags.bundle.
  197. Mar 18, 2013 18:29:54:514 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
  198. Mar 18, 2013 18:29:54:514 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\MoviePosterDB.bundle.
  199. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle.
  200. Mar 18, 2013 18:29:54:514 [5260] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
  201. Mar 18, 2013 18:29:54:514 [5260] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\OpenSubtitles.bundle.
  202. Mar 18, 2013 18:29:54:514 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
  203. Mar 18, 2013 18:29:54:514 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Pandora.bundle.
  204. Mar 18, 2013 18:29:54:514 [3412] DEBUG - We're going to add C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle\Contents/Site Configurations as a location for site configurations.
  205. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Adding directory for site configurations: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle\Contents/Site Configurations
  206. Mar 18, 2013 18:29:54:514 [3412] DEBUG - The path changed: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle\Contents/Site Configurations.
  207. Mar 18, 2013 18:29:54:514 [3412] DEBUG - This corresponds to site configuration map: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle\Contents/Site Configurations (directory: 1)
  208. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.plugins.netflix] yet.
  209. Mar 18, 2013 18:29:54:514 [3412] DEBUG -  * Added Netflix at /video/netflix
  210. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\PersonalMedia.bundle.
  211. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
  212. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\PlexThemeMusic.bundle.
  213. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
  214. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Scanners.bundle.
  215. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
  216. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\SiteConfigurations.bundle.
  217. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Spotify.bundle.
  218. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Starting file watcher for com.plexapp.plugins.spotify
  219. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files (x86)\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Framework.bundle\Contents\Resources\Versions\2\Python/bootstrap.py" "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Spotify.bundle"
  220. Mar 18, 2013 18:29:54:514 [3412] DEBUG - Spawned plug-in com.plexapp.plugins.spotify with PID 5304
  221. Mar 18, 2013 18:29:54:514 [3412] DEBUG - [com.plexapp.plugins.spotify] Sent command: GET /:/prefixes
  222.  
  223. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.plugins.pandora] yet.
  224. Mar 18, 2013 18:29:54:530 [1004] DEBUG -  * Added Pandora at /music/pandora
  225. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\The-Movie-Database.bundle.
  226. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
  227. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\TheTVDB.bundle.
  228. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
  229. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\TVRage.bundle.
  230. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.tvrage] yet.
  231. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\WebClient.bundle.
  232. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\WebManager.bundle.
  233. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Wikipedia.bundle.
  234. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.wikipedia] yet.
  235. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\YouTube.bundle.
  236. Mar 18, 2013 18:29:54:530 [1004] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.plugins.youtube] yet.
  237. Mar 18, 2013 18:29:54:530 [1004] DEBUG -  * Added YouTube at /video/youtube
  238. Mar 18, 2013 18:29:54:546 [5260] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
  239. Mar 18, 2013 18:29:54:546 [5104] DEBUG - [com.plexapp.system] HTTP reply status 200, with 86152 bytes of content.
  240. Mar 18, 2013 18:29:54:639 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: sdResolveRef = 0x03ECC948, flags = 0, interfaceIndex = 15, errorCode = 0
  241. Mar 18, 2013 18:29:54:639 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: fullname = YGGDRASIL._plexmediasvr._tcp.local., hosttarget = YGGDRASIL.local., tcpport = 36990, txtLen = 113
  242. Mar 18, 2013 18:29:54:639 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: resolved TXT record for YGGDRASIL._plexmediasvr._tcp.local.: host = YGGDRASIL.local., machineIdentifier = 6e186c42359e78e9c87c7998009a151e91cc6c8b, version = 0.9.7.17-1f0b170
  243. Mar 18, 2013 18:29:54:686 [3164] DEBUG - Request: GET /library/sections [192.168.1.65:64283] (5 live)
  244. Mar 18, 2013 18:29:54:686 [3164] DEBUG - It took 0.000000 sec to serialize a list with 5 elements.
  245. Mar 18, 2013 18:29:54:686 [3164] DEBUG - Request: GET /channels/all [192.168.1.65:64284] (5 live)
  246. Mar 18, 2013 18:29:54:686 [3164] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.
  247. Mar 18, 2013 18:29:55:232 [4052] DEBUG - File C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle\Contents/Site Configurations changed: 1362968900 => 0.
  248. Mar 18, 2013 18:29:55:232 [4052] DEBUG - The path changed: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle\Contents/Site Configurations.
  249. Mar 18, 2013 18:29:55:232 [4052] DEBUG - This corresponds to site configuration map: C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Netflix.bundle\Contents/Site Configurations (directory: 1)
  250. Mar 18, 2013 18:29:55:575 [0932] DEBUG - Request: GET /system/messaging/clear_events/com.plexapp.plugins.spotify [127.0.0.1:58529] (5 live)
  251. Mar 18, 2013 18:29:55:575 [0932] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.plugins.spotify
  252. Mar 18, 2013 18:29:55:575 [0932] DEBUG - HTTP requesting to: http://127.0.0.1:58479/system/messaging/clear_events/com.plexapp.plugins.spotify
  253. Mar 18, 2013 18:29:55:653 [0932] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
  254. Mar 18, 2013 18:29:55:762 [5104] DEBUG - Request: GET /:/plugins/com.plexapp.system/resourceHashes [127.0.0.1:58532] (5 live)
  255. Mar 18, 2013 18:29:55:762 [5104] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
  256. Mar 18, 2013 18:29:55:762 [5104] DEBUG - HTTP requesting to: http://127.0.0.1:58479/:/plugins/com.plexapp.system/resourceHashes
  257. Mar 18, 2013 18:29:55:840 [5104] DEBUG - [com.plexapp.system] HTTP reply status 200, with 25379 bytes of content.
  258. Mar 18, 2013 18:29:55:872 [5224] DEBUG - Request: GET / [127.0.0.1:58535] (5 live)
  259. Mar 18, 2013 18:29:55:872 [5224] DEBUG - It took 0.000000 sec to serialize a list with 9 elements.
  260. Mar 18, 2013 18:29:55:872 [5104] DEBUG - Request: GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ [127.0.0.1:58536] (5 live)
  261. Mar 18, 2013 18:29:55:872 [5104] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
  262. Mar 18, 2013 18:29:55:887 [5104] DEBUG - HTTP requesting to: http://127.0.0.1:58479/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
  263. Mar 18, 2013 18:29:55:950 [5104] DEBUG - [com.plexapp.system] HTTP reply status 200, with 86152 bytes of content.
  264. Mar 18, 2013 18:29:56:932 [3656] DEBUG - Bonjour: MonitorTxtRecord - DNSServiceConstructFullName succeeded: YGGDRASIL._plexmediasvr._tcp.local.
  265. Mar 18, 2013 18:29:56:932 [3656] DEBUG - Bonjour: DNSServiceQueryRecord succeeded, QueryRecordContext = 0x0803BED8
  266. Mar 18, 2013 18:29:56:932 [3656] INFO - Bonjour: adding host - name = YGGDRASIL, hostname - YGGDRASIL.local., machineIdentifier = 6e186c42359e78e9c87c7998009a151e91cc6c8b
  267. Mar 18, 2013 18:29:56:932 [3656] DEBUG - Bonjour: DNSServiceResolve_Callback: removing reference 0x03ECC948, deleting ResolveContext instance 0x08046E88
  268. Mar 18, 2013 18:29:56:932 [3656] DEBUG - Bonjour: DNSServiceQueryRecord_Callback succeeded, QueryRecordContext = 0x0803BED8
  269. Mar 18, 2013 18:29:56:932 [3656] INFO - Bonjour: TXT record added for YGGDRASIL._plexmediasvr._tcp.local.
  270. Mar 18, 2013 18:29:56:932 [2888] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=YGGDRASIL.local.
  271. Mar 18, 2013 18:29:56:932 [2888] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=YGGDRASIL.local.
  272. Mar 18, 2013 18:29:56:932 [2888] DEBUG - HTTP requesting to: http://127.0.0.1:58479/system/notify/serverUpdated?host=YGGDRASIL.local.
  273. Mar 18, 2013 18:29:56:932 [3164] DEBUG - Request: GET /servers [127.0.0.1:58540] (5 live)
  274. Mar 18, 2013 18:29:56:932 [3164] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.
  275. Mar 18, 2013 18:29:56:964 [0932] DEBUG - Request: GET /library/sections [192.168.1.100:58543] (5 live)
  276. Mar 18, 2013 18:29:56:964 [0932] DEBUG - It took 0.000000 sec to serialize a list with 5 elements.
  277. Mar 18, 2013 18:29:56:979 [0932] DEBUG - Request: GET /channels/all [192.168.1.100:58544] (5 live)
  278. Mar 18, 2013 18:29:56:979 [0932] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.
  279. Mar 18, 2013 18:29:56:979 [2888] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
  280. Mar 18, 2013 18:29:57:073 [5916] DEBUG - NAT: Initialized UPnP, result: SUCCESS
  281. Mar 18, 2013 18:29:57:073 [5916] DEBUG - NAT: Requested public address via UPnP (success=1)
  282. Mar 18, 2013 18:29:57:073 [5916] DEBUG - NAT: Obtained public address of 184.164.177.94 (failed: 0 myPlex: 0).
  283. Mar 18, 2013 18:29:57:073 [5916] DEBUG - NAT: Checking for change in address: old was  and new is 184.164.177.94.
  284. Mar 18, 2013 18:29:57:073 [5916] INFO - NAT: Mapping changed 184.164.177.94:32400 -> 192.168.1.100:32400
  285. Mar 18, 2013 18:29:57:088 [5916] DEBUG - It took 0.000000 sec to serialize a list with 6 elements.
  286. Mar 18, 2013 18:29:57:088 [5916] DEBUG - MyPlex: Sending Server Info to myPlex (user=entrox@stoleyour.com, ip=, port=32400)
  287. Mar 18, 2013 18:29:57:088 [5916] DEBUG - HTTP requesting to: https://my.plexapp.com/servers.xml?auth_token=uwYZxRhtAoC5oyYRpaih
  288. Mar 18, 2013 18:29:57:993 [5916] DEBUG - MyPlex: Published Mapping State <--- 201
  289. Mar 18, 2013 18:29:57:993 [5916] DEBUG - MyPlex: Got response for 6e186c42359e78e9c87c7998009a151e91cc6c8b
  290. Mar 18, 2013 18:29:57:993 [5916] DEBUG - MyPlex: Registered IP: 184.164.177.94
  291. Mar 18, 2013 18:29:57:993 [5916] DEBUG - MyPlex: Registered Port: 32400
  292. Mar 18, 2013 18:29:57:993 [5916] DEBUG - NAT: Updating mapping complete in thread 03561E68.
  293. Mar 18, 2013 18:29:57:993 [5916] DEBUG - NAT: Deleting puncher.
  294. Mar 18, 2013 18:29:57:993 [5916] DEBUG - NAT: Deleting NATUPnPPuncher.
  295. Mar 18, 2013 18:29:57:993 [5916] DEBUG - NAT: Deleting NATPmPPuncher.
  296. Mar 18, 2013 18:29:57:993 [5916] DEBUG - NAT: We're going to next poll in 19.1 hours.
  297. Mar 18, 2013 18:29:58:227 [5104] DEBUG - Request: GET /library/sections/7/recentlyAddedGrandparents [50.18.148.15:56763] (5 live)
  298. Mar 18, 2013 18:29:58:227 [5104] DEBUG - We found auth token (uwYZxRhtAoC5oyYRpaih), enabling token-based authentication.
  299. Mar 18, 2013 18:29:58:227 [5104] DEBUG - Came in with the master token, authorization succeeded.
  300. Mar 18, 2013 18:29:58:555 [5104] DEBUG - It took 0.281000 sec to serialize a list with 25 elements.
  301. Mar 18, 2013 18:29:59:210 [5224] DEBUG - Request: GET /library/sections/5/recentlyAdded [50.18.148.15:56777] (5 live)
  302. Mar 18, 2013 18:29:59:210 [5224] DEBUG - We found auth token (uwYZxRhtAoC5oyYRpaih), enabling token-based authentication.
  303. Mar 18, 2013 18:29:59:210 [5224] DEBUG - Came in with the master token, authorization succeeded.
  304. Mar 18, 2013 18:29:59:226 [5224] DEBUG - It took 0.000000 sec to serialize a list with 13 elements.
  305. Mar 18, 2013 18:29:59:569 [5104] DEBUG - Request: GET /library/metadata/31086?checkFiles=1&skipRefresh=1 [192.168.1.5:50295] (6 live)
  306. Mar 18, 2013 18:29:59:569 [5224] DEBUG - Request: GET /:/websockets/notifications?X-Plex-Token=FCKqkE7yRmXePkCzhryw [192.168.1.5:50297] (6 live)
  307. Mar 18, 2013 18:29:59:569 [5104] DEBUG -  * checkFiles => 1
  308. Mar 18, 2013 18:29:59:569 [5224] DEBUG -  * X-Plex-Token => FCKqkE7yRmXePkCzhryw
  309. Mar 18, 2013 18:29:59:569 [5104] DEBUG -  * skipRefresh => 1
  310. Mar 18, 2013 18:29:59:569 [5224] DEBUG - Creating a WebSocket for notification stream
  311. Mar 18, 2013 18:29:59:569 [5224] DEBUG - WebSocket: Performing handshake from origin http://192.168.1.100:32400
  312. Mar 18, 2013 18:29:59:569 [5224] DEBUG - Beginning read from WebSocket
  313. Mar 18, 2013 18:29:59:584 [5104] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.
  314. Mar 18, 2013 18:29:59:647 [3164] DEBUG - Request: GET /library/metadata/25982/children [192.168.1.5:50296] (6 live)
  315. Mar 18, 2013 18:29:59:662 [3164] DEBUG - It took 0.000000 sec to serialize a list with 20 elements.
  316. Mar 18, 2013 18:30:00:006 [5104] DEBUG - Request: GET /video/:/transcode/universal/start.m3u8?path=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F31086&session=bpib73lzgze&protocol=hls&offset=0&fastSeek=1&directPlay=0&directStream=1&videoQuality=75&videoResolution=1280x720&subtitleSize=100&audioBoost=100&X-Plex-Token=FCKqkE7yRmXePkCzhryw&X-Plex-Client-Identifier=bpib73lzgze&X-Plex-Product=Web+Client&X-Plex-Device=Windows&X-Plex-Platform=Firefox&X-Plex-Platform-Version=19&X-Plex-Version=0.9.9.11&X-Plex-Device-Name=Plex%2FWeb+(Firefox) [192.168.1.5:50298] (5 live)
  317. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * path => http://127.0.0.1:32400/library/metadata/31086
  318. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * session => bpib73lzgze
  319. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * protocol => hls
  320. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * offset => 0
  321. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * fastSeek => 1
  322. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * directPlay => 0
  323. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * directStream => 1
  324. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * videoQuality => 75
  325. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * videoResolution => 1280x720
  326. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * subtitleSize => 100
  327. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * audioBoost => 100
  328. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Token => FCKqkE7yRmXePkCzhryw
  329. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Client-Identifier => bpib73lzgze
  330. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Product => Web Client
  331. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Device => Windows
  332. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Platform => Firefox
  333. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Platform-Version => 19
  334. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Version => 0.9.9.11
  335. Mar 18, 2013 18:30:00:006 [5104] DEBUG -  * X-Plex-Device-Name => Plex/Web (Firefox)
  336. Mar 18, 2013 18:30:00:006 [5104] DEBUG - Using profile Web
  337. Mar 18, 2013 18:30:00:006 [5104] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/31086
  338. Mar 18, 2013 18:30:00:006 [5104] DEBUG - HTTP requesting to: http://127.0.0.1:32400/library/metadata/31086
  339. Mar 18, 2013 18:30:00:006 [0932] DEBUG - Request: GET /library/metadata/31086 [127.0.0.1:58548] (5 live)
  340. Mar 18, 2013 18:30:00:021 [0932] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.
  341. Mar 18, 2013 18:30:00:021 [5104] DEBUG - Caching document http://127.0.0.1:32400/library/metadata/31086 as 3c5712cd28029b0934187b0986953c80f67d3b45
  342. Mar 18, 2013 18:30:00:021 [5104] DEBUG - Remuxing to hls/mpegts/h264/mp3
  343. Mar 18, 2013 18:30:00:021 [5104] DEBUG - Found session GUID of bpib73lzgze in session start.
  344. Mar 18, 2013 18:30:00:021 [5104] DEBUG - Using session GUID bpib73lzgze for new transcode session.
  345. Mar 18, 2013 18:30:00:021 [5104] DEBUG - Cleaning directory for session bpib73lzgze (C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f)
  346. Mar 18, 2013 18:30:00:021 [5104] DEBUG - Saving session: bpib73lzgze
  347. Mar 18, 2013 18:30:00:068 [5224] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/index.m3u8 [192.168.1.5:50301] (6 live)
  348. Mar 18, 2013 18:30:00:068 [5224] DEBUG - Starting a transcode session bpib73lzgze at offset -1.0 (stopped=1)
  349. Mar 18, 2013 18:30:00:068 [5224] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\PlexNewTranscoder.exe -i "http://127.0.0.1:32400/library/parts/48316/file.mkv" -threads 0 -y -segment_format mpegts -f segment -loglevel quiet -progressurl http://127.0.0.1:32400/video/:/transcode/session/bpib73lzgze/progress -vcodec copy -bsf h264_mp4toannexb -flags -global_header -segment_time 10 -segment_start_number 0 -map 0:0 "-sn" -acodec libmp3lame -aq 2 -map 0:1 media-%05d.ts
  350. Mar 18, 2013 18:30:00:302 [5576] DEBUG - Request: GET /library/parts/48316/file.mkv [127.0.0.1:58549] (6 live)
  351. Mar 18, 2013 18:30:00:489 [0932] DEBUG - Request: GET /library/sections/10/recentlyAdded [50.18.148.15:56781] (6 live)
  352. Mar 18, 2013 18:30:00:489 [0932] DEBUG - We found auth token (uwYZxRhtAoC5oyYRpaih), enabling token-based authentication.
  353. Mar 18, 2013 18:30:00:489 [0932] DEBUG - Came in with the master token, authorization succeeded.
  354. Mar 18, 2013 18:30:00:505 [0932] DEBUG - It took 0.016000 sec to serialize a list with 8 elements.
  355. Mar 18, 2013 18:30:00:598 [5576] WARN - SLOW QUERY: It took 296.000000 ms to retrieve 1 items.
  356. Mar 18, 2013 18:30:00:598 [5576] DEBUG - Content-Length of Z:\Sports\Formula 1\Season 2012\Formula 1 - s2012e18 - Abu Dhabi Grand Prix - pt1.mkv is 4147196287.
  357. Mar 18, 2013 18:30:00:614 [0932] INFO - [Transcoder] Input #0, matroska,webm, from 'http://127.0.0.1:32400/library/parts/48316/file.mkv':
  358. Mar 18, 2013 18:30:00:630 [5576] INFO - [Transcoder]     Stream #0:0: Video: h264 (High), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 25 fps, 25 tbr, 1k tbn, 50 tbc (default)
  359. Mar 18, 2013 18:30:00:630 [0932] INFO - [Transcoder]     Stream #0:1: Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s (default)
  360. Mar 18, 2013 18:30:00:630 [3164] DEBUG - [Transcoder] Average FPS ~ 25 fps, Frame rate ~ 25 fps.
  361. Mar 18, 2013 18:30:00:645 [3164] INFO - [Transcoder] Output #0, segment, to 'media-%05d.ts':
  362. Mar 18, 2013 18:30:00:645 [3164] INFO - [Transcoder]     Stream #0:0: Video: h264, yuv420p, 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 25 fps, 90k tbn, 25 tbc (default)
  363. Mar 18, 2013 18:30:00:645 [3164] INFO - [Transcoder]     Stream #0:1: Audio: mp3, 48000 Hz, stereo, fltp (default)
  364. Mar 18, 2013 18:30:00:645 [3164] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?width=1280&height=720 [127.0.0.1:58557] (7 live)
  365. Mar 18, 2013 18:30:00:645 [3164] DEBUG -  * width => 1280
  366. Mar 18, 2013 18:30:00:645 [3164] DEBUG -  * height => 720
  367. Mar 18, 2013 18:30:00:645 [3164] DEBUG - We want 7 segments ahead, last returned was -1 and max is -1.
  368. Mar 18, 2013 18:30:00:645 [3164] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  369. Mar 18, 2013 18:30:00:661 [5224] DEBUG -  [FFMPEG] Duration: 7543
  370. Mar 18, 2013 18:30:00:661 [5224] DEBUG - Read line, and done: 1
  371. Mar 18, 2013 18:30:00:661 [5224] DEBUG - Building an M3U8 for 7543 total seconds.
  372. Mar 18, 2013 18:30:00:848 [5576] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00000.ts [192.168.1.5:50303] (7 live)
  373. Mar 18, 2013 18:30:00:848 [5576] DEBUG - Asked for segment 0 from session.
  374. Mar 18, 2013 18:30:00:879 [5104] DEBUG - Request: GET /library/sections/11/recentlyAddedGrandparents [50.18.148.15:56783] (7 live)
  375. Mar 18, 2013 18:30:00:879 [5104] DEBUG - We found auth token (uwYZxRhtAoC5oyYRpaih), enabling token-based authentication.
  376. Mar 18, 2013 18:30:00:879 [5104] DEBUG - Came in with the master token, authorization succeeded.
  377. Mar 18, 2013 18:30:00:879 [5104] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.
  378. Mar 18, 2013 18:30:01:144 [5104] DEBUG - Request: GET /library/sections/1/recentlyAddedGrandparents [50.18.148.15:56787] (7 live)
  379. Mar 18, 2013 18:30:01:144 [5104] DEBUG - We found auth token (uwYZxRhtAoC5oyYRpaih), enabling token-based authentication.
  380. Mar 18, 2013 18:30:01:144 [5104] DEBUG - Came in with the master token, authorization succeeded.
  381. Mar 18, 2013 18:30:01:160 [5104] DEBUG - It took 0.000000 sec to serialize a list with 17 elements.
  382. Mar 18, 2013 18:30:01:394 [5576] DEBUG - Returning segment 0 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00000.ts
  383. Mar 18, 2013 18:30:01:394 [5576] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00000.ts is 6898660.
  384. Mar 18, 2013 18:30:01:472 [0932] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50305] (7 live)
  385. Mar 18, 2013 18:30:01:472 [0932] DEBUG -  * time => NaN
  386. Mar 18, 2013 18:30:01:472 [0932] DEBUG -  * duration => NaN
  387. Mar 18, 2013 18:30:01:472 [0932] DEBUG -  * state =>
  388. Mar 18, 2013 18:30:01:472 [0932] DEBUG -  * key => /library/parts/48316/file.mkv
  389. Mar 18, 2013 18:30:01:472 [0932] DEBUG -  * ratingKey => 31086
  390. Mar 18, 2013 18:30:01:472 [0932] INFO - Missing or invalid time parameter
  391. Mar 18, 2013 18:30:01:612 [3164] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00001.ts [192.168.1.5:50306] (7 live)
  392. Mar 18, 2013 18:30:01:612 [3164] DEBUG - Asked for segment 1 from session.
  393. Mar 18, 2013 18:30:02:049 [3164] DEBUG - Returning segment 1 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00001.ts
  394. Mar 18, 2013 18:30:02:049 [3164] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00001.ts is 6006224.
  395. Mar 18, 2013 18:30:02:658 [3164] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.4&size=-22&speed=16.1&remaining=466 [127.0.0.1:58558] (7 live)
  396. Mar 18, 2013 18:30:02:658 [3164] DEBUG -  * progress => 0.4
  397. Mar 18, 2013 18:30:02:658 [3164] DEBUG -  * size => -22
  398. Mar 18, 2013 18:30:02:658 [3164] DEBUG -  * speed => 16.1
  399. Mar 18, 2013 18:30:02:658 [3164] DEBUG -  * remaining => 466
  400. Mar 18, 2013 18:30:03:032 [3164] DEBUG - We want 7 segments ahead, last returned was 1 and max is 2.
  401. Mar 18, 2013 18:30:03:032 [3164] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  402. Mar 18, 2013 18:30:04:670 [5576] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.8&size=-22&speed=14.1&remaining=498 [127.0.0.1:58559] (7 live)
  403. Mar 18, 2013 18:30:04:670 [5576] DEBUG -  * progress => 0.8
  404. Mar 18, 2013 18:30:04:670 [5576] DEBUG -  * size => -22
  405. Mar 18, 2013 18:30:04:670 [5576] DEBUG -  * speed => 14.1
  406. Mar 18, 2013 18:30:04:670 [5576] DEBUG -  * remaining => 498
  407. Mar 18, 2013 18:30:05:107 [0932] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00745.ts [192.168.1.5:50312] (7 live)
  408. Mar 18, 2013 18:30:05:107 [0932] DEBUG - Asked for segment 745 from session.
  409. Mar 18, 2013 18:30:05:169 [5576] DEBUG - We want 7 segments ahead, last returned was 1 and max is 4.
  410. Mar 18, 2013 18:30:05:169 [5576] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  411. Mar 18, 2013 18:30:05:169 [0932] DEBUG - We're in a seek: asked for 745, min/max available was 0/4
  412. Mar 18, 2013 18:30:05:169 [0932] DEBUG - Segment #745 WON.
  413. Mar 18, 2013 18:30:05:169 [0932] DEBUG - Stopping transcode session bpib73lzgze
  414. Mar 18, 2013 18:30:05:169 [0932] DEBUG - Killing job.
  415. Mar 18, 2013 18:30:05:169 [0932] DEBUG - Cleaning directory for session bpib73lzgze (C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f)
  416. Mar 18, 2013 18:30:05:419 [0932] DEBUG - Starting a transcode session bpib73lzgze at offset 7450.0 (stopped=1)
  417. Mar 18, 2013 18:30:05:419 [0932] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\PlexNewTranscoder.exe -ss 7450 -i "http://127.0.0.1:32400/library/parts/48316/file.mkv" -threads 0 -y -segment_format mpegts -f segment -loglevel quiet -progressurl http://127.0.0.1:32400/video/:/transcode/session/bpib73lzgze/progress -vcodec copy -bsf h264_mp4toannexb -flags -global_header -segment_time 10 -segment_start_number 745 -map 0:0 "-sn" -acodec libmp3lame -aq 2 -map 0:1 media-%05d.ts
  418. Mar 18, 2013 18:30:05:653 [5224] DEBUG - Request: GET /library/parts/48316/file.mkv [127.0.0.1:58560] (6 live)
  419. Mar 18, 2013 18:30:05:887 [5224] WARN - SLOW QUERY: It took 234.000000 ms to retrieve 1 items.
  420. Mar 18, 2013 18:30:05:902 [5224] DEBUG - Content-Length of Z:\Sports\Formula 1\Season 2012\Formula 1 - s2012e18 - Abu Dhabi Grand Prix - pt1.mkv is 4147196287.
  421. Mar 18, 2013 18:30:05:918 [5576] DEBUG - Request: GET /library/parts/48316/file.mkv [127.0.0.1:58561] (6 live)
  422. Mar 18, 2013 18:30:05:918 [5576] DEBUG - Request range: 4147173263 to 0
  423. Mar 18, 2013 18:30:06:152 [5576] WARN - SLOW QUERY: It took 234.000000 ms to retrieve 1 items.
  424. Mar 18, 2013 18:30:06:152 [5576] DEBUG - Content-Length of Z:\Sports\Formula 1\Season 2012\Formula 1 - s2012e18 - Abu Dhabi Grand Prix - pt1.mkv is 4147196287.
  425. Mar 18, 2013 18:30:06:183 [3164] DEBUG - Request: GET /library/parts/48316/file.mkv [127.0.0.1:58562] (6 live)
  426. Mar 18, 2013 18:30:06:183 [3164] DEBUG - Request range: 55844 to 0
  427. Mar 18, 2013 18:30:06:417 [3164] WARN - SLOW QUERY: It took 234.000000 ms to retrieve 1 items.
  428. Mar 18, 2013 18:30:06:417 [3164] DEBUG - Content-Length of Z:\Sports\Formula 1\Season 2012\Formula 1 - s2012e18 - Abu Dhabi Grand Prix - pt1.mkv is 4147196287.
  429. Mar 18, 2013 18:30:06:448 [3164] DEBUG - Request: GET /library/parts/48316/file.mkv [127.0.0.1:58563] (7 live)
  430. Mar 18, 2013 18:30:06:448 [3164] DEBUG - Request range: 4090476324 to 0
  431. Mar 18, 2013 18:30:06:682 [3164] WARN - SLOW QUERY: It took 234.000000 ms to retrieve 1 items.
  432. Mar 18, 2013 18:30:06:682 [3164] DEBUG - Content-Length of Z:\Sports\Formula 1\Season 2012\Formula 1 - s2012e18 - Abu Dhabi Grand Prix - pt1.mkv is 4147196287.
  433. Mar 18, 2013 18:30:06:745 [5224] INFO - [Transcoder] Input #0, matroska,webm, from 'http://127.0.0.1:32400/library/parts/48316/file.mkv':
  434. Mar 18, 2013 18:30:06:745 [3164] INFO - [Transcoder]     Stream #0:0: Video: h264 (High), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 25 fps, 25 tbr, 1k tbn, 50 tbc (default)
  435. Mar 18, 2013 18:30:06:745 [5224] INFO - [Transcoder]     Stream #0:1: Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s (default)
  436. Mar 18, 2013 18:30:06:760 [3164] DEBUG - [Transcoder] Average FPS ~ 25 fps, Frame rate ~ 25 fps.
  437. Mar 18, 2013 18:30:06:760 [5224] INFO - [Transcoder] Output #0, segment, to 'media-%05d.ts':
  438. Mar 18, 2013 18:30:06:760 [5224] INFO - [Transcoder]     Stream #0:0: Video: h264, yuv420p, 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 25 fps, 90k tbn, 25 tbc (default)
  439. Mar 18, 2013 18:30:06:760 [5224] INFO - [Transcoder]     Stream #0:1: Audio: mp3, 48000 Hz, stereo, fltp (default)
  440. Mar 18, 2013 18:30:06:760 [5224] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?width=1280&height=720 [127.0.0.1:58571] (7 live)
  441. Mar 18, 2013 18:30:06:760 [5224] DEBUG -  * width => 1280
  442. Mar 18, 2013 18:30:06:760 [5224] DEBUG -  * height => 720
  443. Mar 18, 2013 18:30:06:776 [5224] DEBUG - We want 7 segments ahead, last returned was 1 and max is -1.
  444. Mar 18, 2013 18:30:06:776 [5224] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  445. Mar 18, 2013 18:30:06:792 [0932] DEBUG -  [FFMPEG] Duration: 7543
  446. Mar 18, 2013 18:30:06:792 [0932] DEBUG - Read line, and done: 1
  447. Mar 18, 2013 18:30:07:993 [0932] DEBUG - Returning segment 745 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00745.ts
  448. Mar 18, 2013 18:30:07:993 [0932] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00745.ts is 14049804.
  449. Mar 18, 2013 18:30:08:164 [5224] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50315] (7 live)
  450. Mar 18, 2013 18:30:08:164 [5224] DEBUG -  * time => NaN
  451. Mar 18, 2013 18:30:08:164 [5224] DEBUG -  * duration => NaN
  452. Mar 18, 2013 18:30:08:164 [5224] DEBUG -  * state =>
  453. Mar 18, 2013 18:30:08:164 [5224] DEBUG -  * key => /library/parts/48316/file.mkv
  454. Mar 18, 2013 18:30:08:164 [5224] DEBUG -  * ratingKey => 31086
  455. Mar 18, 2013 18:30:08:164 [5224] INFO - Missing or invalid time parameter
  456. Mar 18, 2013 18:30:08:788 [3164] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.3&size=-22&speed=11.7&remaining=640 [127.0.0.1:58572] (7 live)
  457. Mar 18, 2013 18:30:08:788 [3164] DEBUG -  * progress => 0.3
  458. Mar 18, 2013 18:30:08:788 [3164] DEBUG -  * size => -22
  459. Mar 18, 2013 18:30:08:788 [3164] DEBUG -  * speed => 11.7
  460. Mar 18, 2013 18:30:08:788 [3164] DEBUG -  * remaining => 640
  461. Mar 18, 2013 18:30:09:272 [3164] DEBUG - We want 7 segments ahead, last returned was 745 and max is 746.
  462. Mar 18, 2013 18:30:09:272 [3164] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  463. Mar 18, 2013 18:30:10:926 [5576] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.5&size=-22&speed=8.1&remaining=785 [127.0.0.1:58574] (7 live)
  464. Mar 18, 2013 18:30:10:926 [5576] DEBUG -  * progress => 0.5
  465. Mar 18, 2013 18:30:10:926 [5576] DEBUG -  * size => -22
  466. Mar 18, 2013 18:30:10:926 [5576] DEBUG -  * speed => 8.1
  467. Mar 18, 2013 18:30:10:926 [5576] DEBUG -  * remaining => 785
  468. Mar 18, 2013 18:30:11:238 [5576] DEBUG - We want 7 segments ahead, last returned was 745 and max is 748.
  469. Mar 18, 2013 18:30:11:238 [5576] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  470. Mar 18, 2013 18:30:11:550 [5224] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50320] (6 live)
  471. Mar 18, 2013 18:30:11:550 [5224] DEBUG -  * time => NaN
  472. Mar 18, 2013 18:30:11:550 [5224] DEBUG -  * duration => NaN
  473. Mar 18, 2013 18:30:11:550 [5224] DEBUG -  * state =>
  474. Mar 18, 2013 18:30:11:550 [5224] DEBUG -  * key => /library/parts/48316/file.mkv
  475. Mar 18, 2013 18:30:11:550 [5224] DEBUG -  * ratingKey => 31086
  476. Mar 18, 2013 18:30:11:550 [5224] INFO - Missing or invalid time parameter
  477. Mar 18, 2013 18:30:12:938 [5224] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.9&size=-22&speed=12.2&remaining=770 [127.0.0.1:58575] (6 live)
  478. Mar 18, 2013 18:30:12:938 [5224] DEBUG -  * progress => 0.9
  479. Mar 18, 2013 18:30:12:938 [5224] DEBUG -  * size => -22
  480. Mar 18, 2013 18:30:12:938 [5224] DEBUG -  * speed => 12.2
  481. Mar 18, 2013 18:30:12:938 [5224] DEBUG -  * remaining => 770
  482. Mar 18, 2013 18:30:12:938 [5224] DEBUG - We want 7 segments ahead, last returned was 745 and max is 750.
  483. Mar 18, 2013 18:30:12:938 [5224] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  484. Mar 18, 2013 18:30:14:498 [5224] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=1.2&size=-22&speed=13.7&remaining=577 [127.0.0.1:58576] (5 live)
  485. Mar 18, 2013 18:30:14:498 [5224] DEBUG -  * progress => 1.2
  486. Mar 18, 2013 18:30:14:498 [5224] DEBUG -  * size => -22
  487. Mar 18, 2013 18:30:14:498 [5224] DEBUG -  * speed => 13.7
  488. Mar 18, 2013 18:30:14:498 [5224] DEBUG -  * remaining => 577
  489. Mar 18, 2013 18:30:15:309 [5224] DEBUG - We want 7 segments ahead, last returned was 745 and max is 754.
  490. Mar 18, 2013 18:30:15:309 [5224] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  491. Mar 18, 2013 18:30:15:309 [0932] DEBUG - [Transcoder] Throttle - Going into sloth mode.
  492. Mar 18, 2013 18:30:16:308 [5576] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00746.ts [192.168.1.5:50331] (5 live)
  493. Mar 18, 2013 18:30:16:308 [5576] DEBUG - Asked for segment 746 from session.
  494. Mar 18, 2013 18:30:16:308 [5576] DEBUG - Returning segment 746 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00746.ts
  495. Mar 18, 2013 18:30:16:308 [5576] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00746.ts is 6746380.
  496. Mar 18, 2013 18:30:21:471 [5576] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50354] (4 live)
  497. Mar 18, 2013 18:30:21:471 [5576] DEBUG -  * time => NaN
  498. Mar 18, 2013 18:30:21:471 [5576] DEBUG -  * duration => NaN
  499. Mar 18, 2013 18:30:21:471 [5576] DEBUG -  * state =>
  500. Mar 18, 2013 18:30:21:471 [5576] DEBUG -  * key => /library/parts/48316/file.mkv
  501. Mar 18, 2013 18:30:21:471 [5576] DEBUG -  * ratingKey => 31086
  502. Mar 18, 2013 18:30:21:471 [5576] INFO - Missing or invalid time parameter
  503. Mar 18, 2013 18:30:24:529 [3412] ERROR - GetLineFromPipeWithTimeout timed out
  504. Mar 18, 2013 18:30:24:529 [3412] ERROR - Timeout reading status line from plug-in pipe [com.plexapp.plugins.spotify], we're killing it (got: '').
  505. Mar 18, 2013 18:30:24:529 [3412] DEBUG - Stopping [com.plexapp.plugins.spotify].
  506. Mar 18, 2013 18:30:24:529 [3412] DEBUG - [com.plexapp.plugins.spotify] Killing.
  507. Mar 18, 2013 18:30:24:529 [3412] ERROR - Error received reading configuration for com.plexapp.plugins.spotify
  508. Mar 18, 2013 18:30:24:529 [3412] DEBUG - Stopping [com.plexapp.plugins.spotify].
  509. Mar 18, 2013 18:30:24:607 [5228] DEBUG - Done waiting for all plug-ins to start.
  510. Mar 18, 2013 18:30:24:607 [5228] DEBUG - Bonjour: updating PMS service record
  511. Mar 18, 2013 18:30:24:607 [5228] INFO - Bonjour: machineIdentifier = 6e186c42359e78e9c87c7998009a151e91cc6c8b, version = 0.9.7.17-1f0b170, playersModified = 1363645824.0
  512. Mar 18, 2013 18:30:24:607 [5640] DEBUG - HTTP requesting to: https://my.plexapp.com/servers/6e186c42359e78e9c87c7998009a151e91cc6c8b/sync_lists?auth_token=uwYZxRhtAoC5oyYRpaih
  513. Mar 18, 2013 18:30:24:607 [5228] DEBUG - Bonjour: DNSServiceUpdateRecord succeeded
  514. Mar 18, 2013 18:30:24:607 [3656] DEBUG - Bonjour: DNSServiceQueryRecord_Callback succeeded, QueryRecordContext = 0x0803BED8
  515. Mar 18, 2013 18:30:24:607 [3656] INFO - Bonjour: TXT record removed for YGGDRASIL._plexmediasvr._tcp.local.
  516. Mar 18, 2013 18:30:24:607 [3656] DEBUG - Bonjour: DNSServiceQueryRecord_Callback succeeded, QueryRecordContext = 0x0803BED8
  517. Mar 18, 2013 18:30:24:607 [3656] INFO - Bonjour: TXT record added for YGGDRASIL._plexmediasvr._tcp.local.
  518. Mar 18, 2013 18:30:24:607 [1400] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=YGGDRASIL.local.
  519. Mar 18, 2013 18:30:24:607 [1400] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=YGGDRASIL.local.
  520. Mar 18, 2013 18:30:24:607 [1400] DEBUG - HTTP requesting to: http://127.0.0.1:58479/system/notify/serverUpdated?host=YGGDRASIL.local.
  521. Mar 18, 2013 18:30:24:607 [0932] DEBUG - Request: GET /servers [127.0.0.1:58582] (4 live)
  522. Mar 18, 2013 18:30:24:607 [0932] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.
  523. Mar 18, 2013 18:30:24:622 [5224] DEBUG - Request: GET /library/sections [192.168.1.65:64290] (4 live)
  524. Mar 18, 2013 18:30:24:622 [5224] DEBUG - It took 0.000000 sec to serialize a list with 5 elements.
  525. Mar 18, 2013 18:30:24:638 [5224] DEBUG - Request: GET /channels/all [192.168.1.65:64291] (4 live)
  526. Mar 18, 2013 18:30:24:638 [5224] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.
  527. Mar 18, 2013 18:30:24:638 [0932] DEBUG - Request: GET /library/sections [192.168.1.100:58585] (4 live)
  528. Mar 18, 2013 18:30:24:638 [0932] DEBUG - It took 0.000000 sec to serialize a list with 5 elements.
  529. Mar 18, 2013 18:30:24:654 [0932] DEBUG - Request: GET /channels/all [192.168.1.100:58586] (4 live)
  530. Mar 18, 2013 18:30:24:654 [0932] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.
  531. Mar 18, 2013 18:30:24:654 [1400] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
  532. Mar 18, 2013 18:30:25:184 [5640] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
  533. Mar 18, 2013 18:30:25:184 [5640] INFO - Sync: analyzed 0 sync list(s) with 0 sync items(s)
  534. Mar 18, 2013 18:30:26:307 [0932] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00747.ts [192.168.1.5:50384] (4 live)
  535. Mar 18, 2013 18:30:26:307 [0932] DEBUG - Asked for segment 747 from session.
  536. Mar 18, 2013 18:30:26:307 [0932] DEBUG - Returning segment 747 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00747.ts
  537. Mar 18, 2013 18:30:26:307 [0932] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00747.ts is 7204724.
  538. Mar 18, 2013 18:30:31:471 [5576] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50416] (4 live)
  539. Mar 18, 2013 18:30:31:471 [5576] DEBUG -  * time => NaN
  540. Mar 18, 2013 18:30:31:471 [5576] DEBUG -  * duration => NaN
  541. Mar 18, 2013 18:30:31:471 [5576] DEBUG -  * state =>
  542. Mar 18, 2013 18:30:31:471 [5576] DEBUG -  * key => /library/parts/48316/file.mkv
  543. Mar 18, 2013 18:30:31:471 [5576] DEBUG -  * ratingKey => 31086
  544. Mar 18, 2013 18:30:31:471 [5576] INFO - Missing or invalid time parameter
  545. Mar 18, 2013 18:30:36:213 [0932] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00748.ts [192.168.1.5:50455] (3 live)
  546. Mar 18, 2013 18:30:36:213 [0932] DEBUG - Asked for segment 748 from session.
  547. Mar 18, 2013 18:30:36:213 [0932] DEBUG - Returning segment 748 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00748.ts
  548. Mar 18, 2013 18:30:36:213 [0932] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00748.ts is 4263652.
  549. Mar 18, 2013 18:30:41:471 [0932] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50471] (3 live)
  550. Mar 18, 2013 18:30:41:471 [0932] DEBUG -  * time => NaN
  551. Mar 18, 2013 18:30:41:471 [0932] DEBUG -  * duration => NaN
  552. Mar 18, 2013 18:30:41:471 [0932] DEBUG -  * state =>
  553. Mar 18, 2013 18:30:41:471 [0932] DEBUG -  * key => /library/parts/48316/file.mkv
  554. Mar 18, 2013 18:30:41:471 [0932] DEBUG -  * ratingKey => 31086
  555. Mar 18, 2013 18:30:41:471 [0932] INFO - Missing or invalid time parameter
  556. Mar 18, 2013 18:30:46:307 [2668] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00749.ts [192.168.1.5:50472] (3 live)
  557. Mar 18, 2013 18:30:46:307 [2668] DEBUG - Asked for segment 749 from session.
  558. Mar 18, 2013 18:30:46:307 [2668] DEBUG - Returning segment 749 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00749.ts
  559. Mar 18, 2013 18:30:46:307 [2668] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00749.ts is 4769748.
  560. Mar 18, 2013 18:30:51:486 [0932] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50475] (3 live)
  561. Mar 18, 2013 18:30:51:486 [0932] DEBUG -  * time => NaN
  562. Mar 18, 2013 18:30:51:486 [0932] DEBUG -  * duration => NaN
  563. Mar 18, 2013 18:30:51:486 [0932] DEBUG -  * state =>
  564. Mar 18, 2013 18:30:51:486 [0932] DEBUG -  * key => /library/parts/48316/file.mkv
  565. Mar 18, 2013 18:30:51:486 [0932] DEBUG -  * ratingKey => 31086
  566. Mar 18, 2013 18:30:51:486 [0932] INFO - Missing or invalid time parameter
  567. Mar 18, 2013 18:30:56:306 [0932] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00750.ts [192.168.1.5:50477] (3 live)
  568. Mar 18, 2013 18:30:56:306 [0932] DEBUG - Asked for segment 750 from session.
  569. Mar 18, 2013 18:30:56:306 [0932] DEBUG - Returning segment 750 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00750.ts
  570. Mar 18, 2013 18:30:56:306 [0932] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00750.ts is 3875244.
  571. Mar 18, 2013 18:31:01:485 [4480] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50479] (3 live)
  572. Mar 18, 2013 18:31:01:485 [4480] DEBUG -  * time => NaN
  573. Mar 18, 2013 18:31:01:485 [4480] DEBUG -  * duration => NaN
  574. Mar 18, 2013 18:31:01:485 [4480] DEBUG -  * state =>
  575. Mar 18, 2013 18:31:01:485 [4480] DEBUG -  * key => /library/parts/48316/file.mkv
  576. Mar 18, 2013 18:31:01:485 [4480] DEBUG -  * ratingKey => 31086
  577. Mar 18, 2013 18:31:01:485 [4480] INFO - Missing or invalid time parameter
  578. Mar 18, 2013 18:31:06:306 [0932] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00751.ts [192.168.1.5:50480] (3 live)
  579. Mar 18, 2013 18:31:06:306 [0932] DEBUG - Asked for segment 751 from session.
  580. Mar 18, 2013 18:31:06:306 [0932] DEBUG - Returning segment 751 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00751.ts
  581. Mar 18, 2013 18:31:06:306 [0932] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00751.ts is 9167444.
  582. Mar 18, 2013 18:31:11:469 [0932] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50484] (3 live)
  583. Mar 18, 2013 18:31:11:469 [0932] DEBUG -  * time => NaN
  584. Mar 18, 2013 18:31:11:469 [0932] DEBUG -  * duration => NaN
  585. Mar 18, 2013 18:31:11:469 [0932] DEBUG -  * state =>
  586. Mar 18, 2013 18:31:11:485 [0932] DEBUG -  * key => /library/parts/48316/file.mkv
  587. Mar 18, 2013 18:31:11:485 [0932] DEBUG -  * ratingKey => 31086
  588. Mar 18, 2013 18:31:11:485 [0932] INFO - Missing or invalid time parameter
  589. Mar 18, 2013 18:31:21:485 [2108] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50489] (3 live)
  590. Mar 18, 2013 18:31:21:485 [2108] DEBUG -  * time => NaN
  591. Mar 18, 2013 18:31:21:485 [2108] DEBUG -  * duration => NaN
  592. Mar 18, 2013 18:31:21:485 [2108] DEBUG -  * state =>
  593. Mar 18, 2013 18:31:21:485 [2108] DEBUG -  * key => /library/parts/48316/file.mkv
  594. Mar 18, 2013 18:31:21:485 [2108] DEBUG -  * ratingKey => 31086
  595. Mar 18, 2013 18:31:21:485 [2108] INFO - Missing or invalid time parameter
  596. Mar 18, 2013 18:31:23:310 [3648] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00752.ts [192.168.1.5:50490] (3 live)
  597. Mar 18, 2013 18:31:23:310 [3648] DEBUG - Asked for segment 752 from session.
  598. Mar 18, 2013 18:31:23:310 [3648] DEBUG - Returning segment 752 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00752.ts
  599. Mar 18, 2013 18:31:23:310 [3648] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00752.ts is 5318708.
  600. Mar 18, 2013 18:31:31:484 [2108] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50493] (3 live)
  601. Mar 18, 2013 18:31:31:484 [2108] DEBUG -  * time => NaN
  602. Mar 18, 2013 18:31:31:484 [2108] DEBUG -  * duration => NaN
  603. Mar 18, 2013 18:31:31:484 [2108] DEBUG -  * state =>
  604. Mar 18, 2013 18:31:31:484 [2108] DEBUG -  * key => /library/parts/48316/file.mkv
  605. Mar 18, 2013 18:31:31:484 [2108] DEBUG -  * ratingKey => 31086
  606. Mar 18, 2013 18:31:31:484 [2108] INFO - Missing or invalid time parameter
  607. Mar 18, 2013 18:31:33:309 [3648] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00753.ts [192.168.1.5:50494] (3 live)
  608. Mar 18, 2013 18:31:33:309 [3648] DEBUG - Asked for segment 753 from session.
  609. Mar 18, 2013 18:31:33:309 [3648] DEBUG - Returning segment 753 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00753.ts
  610. Mar 18, 2013 18:31:33:309 [3648] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00753.ts is 2979236.
  611. Mar 18, 2013 18:31:38:052 [3648] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00754.ts [192.168.1.5:50495] (3 live)
  612. Mar 18, 2013 18:31:38:052 [3648] DEBUG - Asked for segment 754 from session.
  613. Mar 18, 2013 18:31:38:052 [3648] DEBUG - Returning segment 754 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00754.ts
  614. Mar 18, 2013 18:31:38:052 [3648] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00754.ts is 242896.
  615. Mar 18, 2013 18:31:41:484 [4892] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50497] (3 live)
  616. Mar 18, 2013 18:31:41:484 [4892] DEBUG -  * time => NaN
  617. Mar 18, 2013 18:31:41:484 [4892] DEBUG -  * duration => NaN
  618. Mar 18, 2013 18:31:41:484 [4892] DEBUG -  * state =>
  619. Mar 18, 2013 18:31:41:484 [4892] DEBUG -  * key => /library/parts/48316/file.mkv
  620. Mar 18, 2013 18:31:41:484 [4892] DEBUG -  * ratingKey => 31086
  621. Mar 18, 2013 18:31:41:484 [4892] INFO - Missing or invalid time parameter
  622. Mar 18, 2013 18:31:50:766 [0832] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48316%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50501] (3 live)
  623. Mar 18, 2013 18:31:50:766 [0832] DEBUG -  * time => NaN
  624. Mar 18, 2013 18:31:50:766 [0832] DEBUG -  * duration => NaN
  625. Mar 18, 2013 18:31:50:766 [0832] DEBUG -  * state =>
  626. Mar 18, 2013 18:31:50:766 [0832] DEBUG -  * key => /library/parts/48316/file.mkv
  627. Mar 18, 2013 18:31:50:766 [0832] DEBUG -  * ratingKey => 31086
  628. Mar 18, 2013 18:31:50:766 [0832] INFO - Missing or invalid time parameter
  629. Mar 18, 2013 18:31:51:015 [4892] DEBUG - Request: GET /video/:/transcode/universal/start.m3u8?path=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F31086&session=bpib73lzgze&protocol=hls&offset=0&fastSeek=1&directPlay=0&directStream=1&videoQuality=75&videoResolution=1280x720&subtitleSize=100&audioBoost=100&X-Plex-Token=FCKqkE7yRmXePkCzhryw&X-Plex-Client-Identifier=bpib73lzgze&X-Plex-Product=Web+Client&X-Plex-Device=Windows&X-Plex-Platform=Firefox&X-Plex-Platform-Version=19&X-Plex-Version=0.9.9.11&X-Plex-Device-Name=Plex%2FWeb+(Firefox) [192.168.1.5:50503] (3 live)
  630. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * path => http://127.0.0.1:32400/library/metadata/31086
  631. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * session => bpib73lzgze
  632. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * protocol => hls
  633. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * offset => 0
  634. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * fastSeek => 1
  635. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * directPlay => 0
  636. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * directStream => 1
  637. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * videoQuality => 75
  638. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * videoResolution => 1280x720
  639. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * subtitleSize => 100
  640. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * audioBoost => 100
  641. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Token => FCKqkE7yRmXePkCzhryw
  642. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Client-Identifier => bpib73lzgze
  643. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Product => Web Client
  644. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Device => Windows
  645. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Platform => Firefox
  646. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Platform-Version => 19
  647. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Version => 0.9.9.11
  648. Mar 18, 2013 18:31:51:015 [4892] DEBUG -  * X-Plex-Device-Name => Plex/Web (Firefox)
  649. Mar 18, 2013 18:31:51:015 [4892] DEBUG - Using profile Web
  650. Mar 18, 2013 18:31:51:015 [4892] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/31086
  651. Mar 18, 2013 18:31:51:015 [4892] DEBUG - Cache hit for document http://127.0.0.1:32400/library/metadata/31086
  652. Mar 18, 2013 18:31:51:015 [4892] DEBUG - HTTP requesting to: http://127.0.0.1:32400/library/metadata/31086
  653. Mar 18, 2013 18:31:51:015 [5356] DEBUG - Request: GET /library/metadata/31086 [127.0.0.1:58592] (4 live)
  654. Mar 18, 2013 18:31:51:031 [5356] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.
  655. Mar 18, 2013 18:31:51:031 [4892] DEBUG - Caching document http://127.0.0.1:32400/library/metadata/31086 as 3c5712cd28029b0934187b0986953c80f67d3b45
  656. Mar 18, 2013 18:31:51:031 [4892] DEBUG - Remuxing to hls/mpegts/h264/mp3
  657. Mar 18, 2013 18:31:51:031 [4892] DEBUG - Found session GUID of bpib73lzgze in session start.
  658. Mar 18, 2013 18:31:51:031 [4892] DEBUG - Using session GUID bpib73lzgze for new transcode session.
  659. Mar 18, 2013 18:31:51:031 [4892] DEBUG - Using existing transcode session.
  660. Mar 18, 2013 18:31:51:093 [0832] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/index.m3u8 [192.168.1.5:50504] (4 live)
  661. Mar 18, 2013 18:31:51:093 [0832] DEBUG - Building an M3U8 for 7543 total seconds.
  662. Mar 18, 2013 18:31:51:249 [0832] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00000.ts [192.168.1.5:50505] (4 live)
  663. Mar 18, 2013 18:31:51:249 [0832] DEBUG - Asked for segment 0 from session.
  664. Mar 18, 2013 18:31:51:265 [0832] DEBUG - We're in a seek: asked for 0, min/max available was 745/754
  665. Mar 18, 2013 18:31:51:265 [0832] DEBUG - Segment #0 WON.
  666. Mar 18, 2013 18:31:51:265 [0832] DEBUG - Stopping transcode session bpib73lzgze
  667. Mar 18, 2013 18:31:51:265 [0832] DEBUG - Killing job.
  668. Mar 18, 2013 18:31:51:265 [0832] DEBUG - Cleaning directory for session bpib73lzgze (C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f)
  669. Mar 18, 2013 18:31:51:281 [0832] DEBUG - Starting a transcode session bpib73lzgze at offset 0.0 (stopped=1)
  670. Mar 18, 2013 18:31:51:281 [0832] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\PlexNewTranscoder.exe -i "http://127.0.0.1:32400/library/parts/48316/file.mkv" -threads 0 -y -segment_format mpegts -f segment -loglevel quiet -progressurl http://127.0.0.1:32400/video/:/transcode/session/bpib73lzgze/progress -vcodec copy -bsf h264_mp4toannexb -flags -global_header -segment_time 10 -segment_start_number 0 -map 0:0 "-sn" -acodec libmp3lame -aq 2 -map 0:1 media-%05d.ts
  671. Mar 18, 2013 18:31:51:515 [5356] DEBUG - Request: GET /library/parts/48316/file.mkv [127.0.0.1:58593] (4 live)
  672. Mar 18, 2013 18:31:51:749 [5356] WARN - SLOW QUERY: It took 234.000000 ms to retrieve 1 items.
  673. Mar 18, 2013 18:31:51:749 [5356] DEBUG - Content-Length of Z:\Sports\Formula 1\Season 2012\Formula 1 - s2012e18 - Abu Dhabi Grand Prix - pt1.mkv is 4147196287.
  674. Mar 18, 2013 18:31:51:780 [5356] INFO - [Transcoder] Input #0, matroska,webm, from 'http://127.0.0.1:32400/library/parts/48316/file.mkv':
  675. Mar 18, 2013 18:31:51:780 [4892] INFO - [Transcoder]     Stream #0:0: Video: h264 (High), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 25 fps, 25 tbr, 1k tbn, 50 tbc (default)
  676. Mar 18, 2013 18:31:51:780 [5356] INFO - [Transcoder]     Stream #0:1: Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s (default)
  677. Mar 18, 2013 18:31:51:795 [5356] DEBUG - [Transcoder] Average FPS ~ 25 fps, Frame rate ~ 25 fps.
  678. Mar 18, 2013 18:31:51:795 [5356] INFO - [Transcoder] Output #0, segment, to 'media-%05d.ts':
  679. Mar 18, 2013 18:31:51:795 [4892] INFO - [Transcoder]     Stream #0:0: Video: h264, yuv420p, 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 25 fps, 90k tbn, 25 tbc (default)
  680. Mar 18, 2013 18:31:51:795 [4892] INFO - [Transcoder]     Stream #0:1: Audio: mp3, 48000 Hz, stereo, fltp (default)
  681. Mar 18, 2013 18:31:51:795 [5356] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?width=1280&height=720 [127.0.0.1:58601] (5 live)
  682. Mar 18, 2013 18:31:51:795 [5356] DEBUG -  * width => 1280
  683. Mar 18, 2013 18:31:51:795 [5356] DEBUG -  * height => 720
  684. Mar 18, 2013 18:31:51:795 [5356] DEBUG - We want 7 segments ahead, last returned was 754 and max is -1.
  685. Mar 18, 2013 18:31:51:795 [5356] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  686. Mar 18, 2013 18:31:51:811 [0832] DEBUG -  [FFMPEG] Duration: 7543
  687. Mar 18, 2013 18:31:51:811 [0832] DEBUG - Read line, and done: 1
  688. Mar 18, 2013 18:31:52:373 [5356] DEBUG - Request: GET /servers [127.0.0.1:58602] (5 live)
  689. Mar 18, 2013 18:31:52:373 [5356] DEBUG - It took 0.000000 sec to serialize a list with 2 elements.
  690. Mar 18, 2013 18:31:52:373 [5356] DEBUG - Request: GET /library/sections [192.168.1.100:58603] (5 live)
  691. Mar 18, 2013 18:31:52:373 [5356] DEBUG - It took 0.000000 sec to serialize a list with 5 elements.
  692. Mar 18, 2013 18:31:52:388 [4892] DEBUG - Request: GET /channels/all [192.168.1.100:58604] (5 live)
  693. Mar 18, 2013 18:31:52:388 [4892] DEBUG - It took 0.000000 sec to serialize a list with 3 elements.
  694. Mar 18, 2013 18:31:52:809 [0832] DEBUG - Returning segment 0 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00000.ts
  695. Mar 18, 2013 18:31:52:809 [0832] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00000.ts is 6898660.
  696. Mar 18, 2013 18:31:52:934 [5356] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48317%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50507] (5 live)
  697. Mar 18, 2013 18:31:52:934 [5356] DEBUG -  * time => NaN
  698. Mar 18, 2013 18:31:52:950 [5356] DEBUG -  * duration => NaN
  699. Mar 18, 2013 18:31:52:950 [5356] DEBUG -  * state =>
  700. Mar 18, 2013 18:31:52:950 [5356] DEBUG -  * key => /library/parts/48317/file.mkv
  701. Mar 18, 2013 18:31:52:950 [5356] DEBUG -  * ratingKey => 31086
  702. Mar 18, 2013 18:31:52:950 [5356] INFO - Missing or invalid time parameter
  703. Mar 18, 2013 18:31:53:075 [0832] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00001.ts [192.168.1.5:50509] (5 live)
  704. Mar 18, 2013 18:31:53:075 [0832] DEBUG - Asked for segment 1 from session.
  705. Mar 18, 2013 18:31:53:293 [0832] DEBUG - Returning segment 1 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00001.ts
  706. Mar 18, 2013 18:31:53:293 [0832] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00001.ts is 6006224.
  707. Mar 18, 2013 18:31:53:808 [4892] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.4&size=-22&speed=15.0&remaining=501 [127.0.0.1:58607] (5 live)
  708. Mar 18, 2013 18:31:53:808 [4892] DEBUG -  * progress => 0.4
  709. Mar 18, 2013 18:31:53:808 [4892] DEBUG -  * size => -22
  710. Mar 18, 2013 18:31:53:808 [4892] DEBUG -  * speed => 15.0
  711. Mar 18, 2013 18:31:53:808 [4892] DEBUG -  * remaining => 501
  712. Mar 18, 2013 18:31:53:808 [4892] DEBUG - We want 7 segments ahead, last returned was 1 and max is 1.
  713. Mar 18, 2013 18:31:53:808 [4892] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  714. Mar 18, 2013 18:31:55:820 [5356] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.7&size=-22&speed=10.4&remaining=609 [127.0.0.1:58608] (5 live)
  715. Mar 18, 2013 18:31:55:820 [5356] DEBUG -  * progress => 0.7
  716. Mar 18, 2013 18:31:55:820 [5356] DEBUG -  * size => -22
  717. Mar 18, 2013 18:31:55:820 [5356] DEBUG -  * speed => 10.4
  718. Mar 18, 2013 18:31:55:820 [5356] DEBUG -  * remaining => 609
  719. Mar 18, 2013 18:31:55:820 [5356] DEBUG - We want 7 segments ahead, last returned was 1 and max is 4.
  720. Mar 18, 2013 18:31:55:820 [5356] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  721. Mar 18, 2013 18:32:02:123 [5356] DEBUG - Request: GET /video/:/transcode/universal/session/bpib73lzgze/base/00002.ts [192.168.1.5:50510] (5 live)
  722. Mar 18, 2013 18:32:02:123 [5356] DEBUG - Asked for segment 2 from session.
  723. Mar 18, 2013 18:32:02:123 [5356] DEBUG - Returning segment 2 from session: C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00002.ts
  724. Mar 18, 2013 18:32:02:123 [5356] DEBUG - Content-Length of C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f\media-00002.ts is 5264000.
  725. Mar 18, 2013 18:32:02:622 [5356] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=0.9&size=-22&speed=2.4&remaining=1916 [127.0.0.1:58610] (5 live)
  726. Mar 18, 2013 18:32:02:622 [5356] DEBUG -  * progress => 0.9
  727. Mar 18, 2013 18:32:02:622 [5356] DEBUG -  * size => -22
  728. Mar 18, 2013 18:32:02:622 [5356] DEBUG -  * speed => 2.4
  729. Mar 18, 2013 18:32:02:622 [5356] DEBUG -  * remaining => 1916
  730. Mar 18, 2013 18:32:02:622 [5356] DEBUG - We want 7 segments ahead, last returned was 2 and max is 5.
  731. Mar 18, 2013 18:32:02:622 [5356] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  732. Mar 18, 2013 18:32:04:634 [5004] DEBUG - Request: PUT /video/:/transcode/session/bpib73lzgze/progress?progress=1.3&size=-22&speed=17.1&remaining=1775 [127.0.0.1:58611] (4 live)
  733. Mar 18, 2013 18:32:04:634 [5004] DEBUG -  * progress => 1.3
  734. Mar 18, 2013 18:32:04:634 [5004] DEBUG -  * size => -22
  735. Mar 18, 2013 18:32:04:634 [5004] DEBUG -  * speed => 17.1
  736. Mar 18, 2013 18:32:04:634 [5004] DEBUG -  * remaining => 1775
  737. Mar 18, 2013 18:32:05:243 [4496] DEBUG - Request: GET /:/timeline?time=NaN&duration=NaN&state=&key=%2Flibrary%2Fparts%2F48317%2Ffile.mkv&ratingKey=31086 [192.168.1.5:50513] (5 live)
  738. Mar 18, 2013 18:32:05:243 [4496] DEBUG -  * time => NaN
  739. Mar 18, 2013 18:32:05:243 [4496] DEBUG -  * duration => NaN
  740. Mar 18, 2013 18:32:05:243 [4496] DEBUG -  * state =>
  741. Mar 18, 2013 18:32:05:243 [4496] DEBUG -  * key => /library/parts/48317/file.mkv
  742. Mar 18, 2013 18:32:05:243 [4496] DEBUG -  * ratingKey => 31086
  743. Mar 18, 2013 18:32:05:243 [4496] INFO - Missing or invalid time parameter
  744. Mar 18, 2013 18:32:05:352 [5004] DEBUG - We want 7 segments ahead, last returned was 2 and max is 9.
  745. Mar 18, 2013 18:32:05:352 [5004] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  746. Mar 18, 2013 18:32:06:491 [5356] DEBUG - Request: GET /library/metadata/25982?checkFiles=1&skipRefresh=1 [192.168.1.5:50515] (5 live)
  747. Mar 18, 2013 18:32:06:491 [5356] DEBUG -  * checkFiles => 1
  748. Mar 18, 2013 18:32:06:491 [5356] DEBUG -  * skipRefresh => 1
  749. Mar 18, 2013 18:32:06:506 [5356] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.
  750. Mar 18, 2013 18:32:06:787 [5356] DEBUG - Request: GET /library/metadata/25982/children [192.168.1.5:50516] (6 live)
  751. Mar 18, 2013 18:32:06:787 [4496] DEBUG - Request: GET /video/:/transcode/segmented/stop?session=bpib73lzgze [192.168.1.5:50517] (5 live)
  752. Mar 18, 2013 18:32:06:787 [4496] DEBUG -  * session => bpib73lzgze
  753. Mar 18, 2013 18:32:06:787 [0276] DEBUG - Stopping transcode session bpib73lzgze
  754. Mar 18, 2013 18:32:06:787 [0276] DEBUG - Killing job.
  755. Mar 18, 2013 18:32:06:818 [5356] DEBUG - It took 0.000000 sec to serialize a list with 20 elements.
  756. Mar 18, 2013 18:32:07:021 [0276] DEBUG - Cleaning directory for session bpib73lzgze (C:\Users\ADMINI~1\AppData\Local\Temp\2\plex-transcode-bpib73lzgze-9344ebc4-2030-4d7f-b735-be5a783b632f)
  757. Mar 18, 2013 18:32:07:037 [0276] DEBUG - Whacked session, 0 remaining.
  758. Mar 18, 2013 18:32:07:037 [0276] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
  759. Mar 18, 2013 18:32:10:172 [3196] DEBUG - Ordered to stop server.
  760. Mar 18, 2013 18:32:10:172 [4852] DEBUG - Stopping server...
  761. Mar 18, 2013 18:32:10:172 [3720] DEBUG - Sync: cancelCurrentTranscode
  762. Mar 18, 2013 18:32:10:172 [4852] DEBUG - Server stopped.
  763. Mar 18, 2013 18:32:10:172 [4852] DEBUG - NetworkService: Stopping advertisement.
  764. Mar 18, 2013 18:32:10:172 [4852] DEBUG - Killing plug-ins.
  765. Mar 18, 2013 18:32:10:172 [4852] DEBUG - [com.plexapp.system] Killing.
  766. Mar 18, 2013 18:32:10:172 [4852] DEBUG - Waiting for DLNA server to exit.
  767. Mar 18, 2013 18:32:10:874 [3912] DEBUG - DLNA Server exited.
  768. Mar 18, 2013 18:32:10:874 [4852] DEBUG - WebKit: Stream closed.
  769. Mar 18, 2013 18:32:10:874 [4852] DEBUG - WK_BrowseToURL about:blank
  770.  

Replies to Untitled rss

Title Name When
Re: Untitled Silly Dove 8 Years ago.

Reply to "Untitled"

Here you can reply to the paste above

Powered By Stikked