Well, thanks to you for looking into it and helping me out, I’ve edited and formatted the logs.
As I said, I went to the “CONVERT VIDEO WHILE RECORDING” option in the Device Settings and set it to OFF, hoping for the best, but it didn’t turn out in the result I was expecting…
The transcode errors seen in the previous logs were still there, no matter the settings I set, I also tried rebooting the Shield in a few cases hoping it was needed for the setting to be digested.
Here are some log excerpts on different settings and cases:
- Convert video while recording:OFF. Result OK -> ncis_shield_noTransc_ok_2.txt (344.0 KB)
- Convert video while recording:OFF. Result FAIL -> ncis_shield_noTransc_fail.txt (138.0 KB)
- Convert video while recording:ON. Result FAIL -> ncis_shield_siTransc_fail.txt (189.1 KB)
- Convert video while recording:ON. Result FAIL -> ncis_shield_siTransc_2_fail.txt (147.3 KB)
- Convert video while recording:OFF. Result FAIL -> cuoco_shield_noTransc_3_fail.txt (93.6 KB)
I then thought about the hardware transcode and tried disabling
- Convert video while recording:OFF | Hardware Transcode:OFF. Result OK -> cuoco_shield_siTransc_HwDis_1_ok.txt (157.9 KB)
Happy about the positive result I deleted the recording, started another one of the same program and got again the error
- Convert video while recording:OFF | Hardware Transcode:OFF. Result FAIL -> cuoco_shield_siTransc_HwDis_1_delete redo_fail.txt (111.8 KB)
Then I decided to get a fresh start and circumscribe the problem to the fewest variables, I installed a Plex Media Server on my Windows PC, no option has been touched, everything is just standard, except:
- Added 1 TV library
- HDHomeRun setup with standard options
- 2 channels configured (RAI1 and RAI2, the first 2 national channels in Italy)
- “CONVERT VIDEO WHILE RECORDING” set to OFF
Recording the current TV program on RAI1 (first channel) almost consistently produces the following result (I say almost cause in a few cases the recording started flawlessly, but it’s rare):
- server log full: conme_windows_noTransc_HwDis_standardSetts_1_fail.txt (173.1 KB)
- server log w/o debug
Sep 13, 2019 14:15:36.273 [12932] INFO - Plex Media Server v1.16.6.1592-b9d49bdb7 - Microsoft PC x64 - build: windows-x86 windows - GMT 02:00
Sep 13, 2019 14:15:36.274 [12932] INFO - Windows version: 6.1 (Build 7601), language en-US
Sep 13, 2019 14:15:36.274 [12932] INFO - 4 3094 MHz processor(s): Architecture=0, Level=21, Revision=12289 Processor Identifier=AMD64 Family 21 Model 48 Stepping 1, AuthenticAMD
Sep 13, 2019 14:15:36.274 [12932] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe"
Sep 13, 2019 14:15:50.108 [12460] ERROR - Error issuing curl_easy_perform(handle): 7
Sep 13, 2019 14:15:50.108 [12460] WARN - HTTP error requesting GET https://87-5-2-87.80d7659250784527a85ecd202da92835.plex.direct:20253 (0, No error) (Failed to connect to 87-5-2-87.80d7659250784527a85ecd202da92835.plex.direct port 20253: Connection refused)
Sep 13, 2019 14:15:50.115 [3940] ERROR - Unable to find title for item of type 5
Sep 13, 2019 14:15:50.116 [3940] ERROR - Unable to find title for item of type 5
Sep 13, 2019 14:15:51.118 [12108] ERROR - [Transcoder] [mpeg2video @ 0230b740] Invalid frame dimensions 0x0.
Sep 13, 2019 14:15:51.247 [13112] ERROR - [Transcoder] [mpeg2video @ 0230b740] Invalid frame dimensions 0x0.
Sep 13, 2019 14:15:54.128 [10652] ERROR - [Transcoder] [mp2 @ 0230d600] Header missing
Sep 13, 2019 14:15:54.134 [13112] ERROR - [Transcoder] [mp2 @ 0230c6c0] Header missing
Sep 13, 2019 14:15:54.138 [10652] ERROR - [Transcoder] [mp2 @ 0230e540] Header missing
Sep 13, 2019 14:15:54.174 [3940] ERROR - Unable to find title for item of type 5
Sep 13, 2019 14:15:54.176 [3940] ERROR - Unable to find title for item of type 5
Sep 13, 2019 14:15:54.456 [10992] INFO - Notification: Recording Vieni da me - Vieni da me -
Sep 13, 2019 14:15:55.198 [10644] ERROR - MoveFileExW(C:\Users\caedium\AppData\Local\Temp\Plx-d2c73872-2ba7-45e3-bff4-ed24bdac4cc9, "C:\Users\caedium\AppData\Local\Plex Media Server\Logs\Plex Transcoder Statistics.log") failed: 0x5
Sep 13, 2019 14:15:55.955 [11896] WARN - Connection Tester: Error testing media server TERMIPLEX in 10048 ms.
Sep 13, 2019 14:15:55.955 [12216] WARN - Connection Tester: Error testing media server SHIELD Plex in 10048 ms.
Sep 13, 2019 14:16:09.489 [10632] WARN - No matches for search.
Sep 13, 2019 14:16:09.491 [10632] DEBUG - DVR:Grabber: Operation for 'Vieni da me - Vieni da me' on channel 001 completed with status complete (Success)
- full log zip: Plex Media Server Logs_2019-09-13_14-27-07.zip (40.4 KB)
Logs taken after cleaning the Logs folder, restarting the server and reproducing the error running the TV program record
Well, right now I’m at a dead end, I have no more options left to try, if somebody from the Plex Team needs more details or tests to be run, please let me know.
In case anybody need it I casually found out why the Shield Plex Server didn’t have Write Access to the NAS, it may happen if in any moment more than one plex server has been run to record using the same folders:
The first plex server that tries to record something in a folder creates a folder called “.grab” with access rights only for the owner of the folder. This means that if a different Plex Server running under different credentials wants to use the same folder, it will fail cause of lack of right over that “.grab” folder. Just deleting that folder and letting the plex server recreate it solves the problem.
