sa2000, sent you the logs over private message.
Thanks for the logs.
The shield is running on v1.18.6.2278-3aca97ad6
Jan 28, 2020 12:28:00.744 [4060] INFO - Plex Media Server v1.18.6.2278-3aca97ad6 - NVIDIA SHIELD Android TV darcy - build: android-aarch64 unknown - GMT -07:00
Jan 28, 2020 12:28:00.744 [4060] INFO - Android version: 9, language: en-US
Jan 28, 2020 12:28:00.744 [4060] INFO - Processor Unknown architecture
I actually cannot see any recordings being made on the Nvidia Shield in the logs.
Nor any grab directories purged
which shows have you recorded and on what machine and when?
Are these shows visible to the Synology but not the Shield?
The last periodic hourly scan on the Nvidia Shield was at 07:14
)
Jan 28, 2020 04:59:09.447 [16097] INFO - It's been 3891 seconds, so we're starting scheduled library update for section 2 (TV Shows)
Jan 28, 2020 05:04:09.448 [16419] INFO - It's been 3898 seconds, so we're starting scheduled library update for section 1 (Movies)
Jan 28, 2020 05:04:09.448 [16419] INFO - It's been 3891 seconds, so we're starting scheduled library update for section 3 (Music)
Jan 28, 2020 06:04:09.484 [17683] INFO - It's been 3893 seconds, so we're starting scheduled library update for section 2 (TV Shows)
Jan 28, 2020 06:09:09.487 [17703] INFO - It's been 3898 seconds, so we're starting scheduled library update for section 1 (Movies)
Jan 28, 2020 06:09:09.487 [17703] INFO - It's been 3891 seconds, so we're starting scheduled library update for section 3 (Music)
Jan 28, 2020 07:09:09.513 [19326] INFO - It's been 3890 seconds, so we're starting scheduled library update for section 2 (TV Shows)
Jan 28, 2020 07:14:09.526 [19579] INFO - It's been 3898 seconds, so we're starting scheduled library update for section 1 (Movies)
Jan 28, 2020 07:14:09.526 [19579] INFO - It's been 3891 seconds, so we're starting scheduled library update for section 3 (Music)
We appear to have stopped the periodic checks that happen every 5 minutes - with last log entry at 07:49
I can see some issues with the certificate.
Could you update to the new beta and see if the hourly library updates happen. Collect the logs soon after the first hour passes
@jamesbigler appears to have been a deadlock of some sort that arose at about 07:53 and after that the periodic checks stopped. I need to see if this happens in the latest alpha build
Just realized I had not sent you the new alpha build for the Shield
I get this error as well, the Media files are on a NAS (NFS Share) but I only have one server and isn’t shared. Is this thread dealing with the error only if people are sharing the paths with other PMS’s? Only difference is that this error is not about failing recordings, its Live TV that causes this (pic shows no TV show title)
Thanks for your guys continue working on this issue. It’s appreciated. I had my first problem with the first alpha you gave me today. I got the same destination error for the first time since my update, but it was trying to record 2 shows at the same time. A single show before and a single show afterwards were recorded correctly so I’m assuming trying to record 2 shows at once caused the old problem. Do we know how this new alpha will react to 2 simultaneous recordings? Anyway I’ll try the new program and see what happens. Thank you;)
The only other case is using a recent build with a faulty fix for the shared paths .gtrab deletion issue as was mentioned here Could Not move recorded media to destination - #154 by gte517z - which you are not
Would need logs after a failed recording. Would be also good to have logs from the server after launch to see if any startup issues.
The issue with the first alpha was that when the .grab directories cleanup routine was run, it purged file for the in-progress recording. The second alpha has a fix for that.
So the parameter here is
- getting the cleanup routine to execute whilst a recording is in place.
I managed to get logs after the error occurred again. Is it safe to attach log files here? I didn’t see any sensitive info, Plex Media Server.1.log has the error.
Jan 29, 2020 21:27:32.334 [0x7f9d1b308700] DEBUG - [FFMPEG] - After avformat_find_stream_info() pos: 0 bytes read:462104 seeks:2 frames:50
Jan 29, 2020 21:27:32.334 [0x7f9d1b308700] DEBUG - [MI] * [mpegts h264/aac] Duration: 2097ms, 1920x1080 (1.777778) 59.940fps, bitrate=881kbps, audio channels=2, optimizedForStreaming=0 ("/plex/Transcode/Sessions/plex-transcode-20a09d09-e2b4-46$
Jan 29, 2020 21:27:32.334 [0x7f9d1b308700] DEBUG - [MI] Chapters had generic titles, clearing.
Jan 29, 2020 21:27:32.334 [0x7f9d1b308700] DEBUG - [MI] Closing input file: "/plex/Transcode/Sessions/plex-transcode-20a09d09-e2b4-46b4-b998-bb95f2ace835/media-00000.ts"
Jan 29, 2020 21:27:32.334 [0x7f9d1b308700] DEBUG - Grabber: Scheduling 'Live TV - Session' for assimilation.
Jan 29, 2020 21:27:32.334 [0x7f9c86ffd700] WARN - Subscription: Couldn't get target root path for subscription 3301
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Grabber: Operation for 'Live TV - Session 4a44900973fd2d861208a06544a3c427' on channel 300 completed with status error (The destination library or location did not exist)
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Activity: Ended activity 09a56f1b-8703-48fb-975e-5faefa42521e.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Grabber: Cleaning up rolling grab for channel 300.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Recorder: No more consumers, stopping.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Recorder: Asked to stop recording 'Live TV - Session 4a44900973fd2d861208a06544a3c427' on channel 300.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Recorder: Stopping transcode session.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Killing job.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Signalling job ID 27335 with 9
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Job was already killed, not killing again.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Stopping transcode session 20a09d09-e2b4-46b4-b998-bb95f2ace835
Jan 29, 2020 21:27:32.335 [0x7f9c457fa700] DEBUG - Cleaning directory for session 20a09d09-e2b4-46b4-b998-bb95f2ace835 (/plex/Transcode/Sessions/plex-transcode-20a09d09-e2b4-46b4-b998-bb95f2ace835)
Jan 29, 2020 21:27:32.335 [0x7f9c417f2700] DEBUG - HTTP requesting GET http://172.16.122.246:34400/discover.json
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Grabber: Freed a tuner on device://tv.plex.grabbers.hdhomerun/2020-01-1KKZ-WJOLP3:32 (now 31 available)
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] WARN - Grabber: Could not compute a final destination, not postprocessing.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Grabber: Operation for 'Live TV - Session 4a44900973fd2d861208a06544a3c427' on channel 300 completed with status error (Could not move recorded media to destination)
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Grabber: Cleaning up rolling grab for channel 300.
Jan 29, 2020 21:27:32.335 [0x7f9c86ffd700] DEBUG - Recorder: No more consumers, stopping.
Recording works so far. Installed the new builds yesterday. Ill try to force (and check) if the library scan on the shield is working properly within the next few hours again.
Update: Ok. No failures any longer. Seems to work perfect here.
Please do attach the logs zip file
Attached to the my original post. Thanks!
With the 1.8.6.2330 builds, I haven’t seen failures yet. I’ve only recorded a couple of shows so far. The NVIDIA Shield also started to index, but again after only a couple of shows in a row, it may not have had a chance to wedge just yet.
I’m going to increase the stuff I’m recording to see if I can trigger issues.
1 Like
I believe we have fixed the issue where other servers were purging the recordings .grab files - but there are still some other causes for these errors that we are looking into
The failure of the Shield to scan new library contents was due to a deadlock situation that arose and that got resolved on the restart
Regarding the channel 300 failure - was this being recorded and at the same time being watched as Live TV and the error arose when the Live TV session ended at 21:27 ?
No so far this only happens with live TV, channel 300 wasn’t being recorded.
Yep it was actually running for that long, 3-930pm.
and that entry and error in the screenshot only pops up there after the error ?
I presume that is a screenshot of “DVR Schedule” - which Plex app / client is the screenshot from?
