No details were shared with me.
When I presented all the information I’d collected, I was immediately told it was fixed by one of the engineers earlier today . It was then mentioned that fix is going to be added to 1.22.2
On new Beta now and haven’t been able to get it to crash at all so far so looks good! Looks like it was related to the new hub stuff.
I’m in the same boat, just updated. Looking good, I’ll be marking the issue as resolved if I can last till the weekend without a crash.
Thank you @ChuckPa and all for all of your efforts!
@ChuckPa close, but no cigar.
plexlogs-2021-03-29.zip (6.2 MB)
Plex settings are currently unavailble.
plexlogs-2021-03-29-2.zip (6.2 MB)
Apparently it hasn’t crashed because I’m seeing other streams still occurring in the logs, but I cannot access the settings at all, nor can I play anything locally.
@ChuckPa Post service restart logs.
Plex Media Server Logs_2021-03-29_19-37-46.zip (5.0 MB)
Plex Media Server Logs_2021-03-29_19-47-27.zip (5.2 MB)
Extremely sluggish after restarting again and nothing can connect.
A minute later:
Now things have stabilized and I was able to start a stream.
Plex Media Server Logs_2021-03-29_19-50-14.zip (5.2 MB)
The update , as was documented in the release notes for 4200 + 4288 + this (should be here too), change the database.
It will take time for the migration to complete. This particular migration is much longer than normal. (40,000 items = 4 minutes on an i7-7700)
I will look at your logs momentarily.
I’ve been very busy today tracking other issues.
PMS on Shield also been dying. Last time I just restarted the Shield and it was fine. This time no dice, server unreachable after restart and waiting quite a while, even the plex client locally on the shield can’t connect.
What’s going on here?
I updated to 4228 before having these problems, and it was working fine as of last night on 4228.
Logs completely unhelpful. This is all I get before it just dies.
Mar 29, 2021 20:23:39.224 [7223] INFO - Plex Media Server v1.22.1.4228-724c56e62 - NVIDIA SHIELD Android TV mdarcy - build: android-aarch64 unknown - GMT -04:00
Mar 29, 2021 20:23:39.224 [7223] INFO - Android version: 9, language: en-US
Mar 29, 2021 20:23:39.225 [7223] INFO - Processor Unknown architecture
Mar 29, 2021 20:23:39.216 [7050] ERROR - Unable to open named mutex 9334581e-7251-4ef7-a8ec-5bfe8e89ff68 with option 1: Function not implemented
Mar 29, 2021 20:23:39.360 [7050] INFO - SQLITE3:0x7fffffff, 283, recovered 930 frames from WAL file /storage/3444-919A/Android/data/com.plexapp.mediaserver.smb/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db-wal
Mar 29, 2021 20:23:40.024 [7050] INFO - SQLITE3:0x7fffffff, 283, recovered 164 frames from WAL file /storage/3444-919A/Android/data/com.plexapp.mediaserver.smb/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.blobs.db-wal
Mar 29, 2021 20:23:40.327 [7050] INFO - [CERT/OCSP] Successfully retrieved response from cache.
Mar 29, 2021 20:23:50.372 [7268] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 29, 2021 20:23:50.372 [7268] WARN - HTTP error requesting POST http://127.0.0.1:32600/devices/discover (7, Couldn’t connect to server) (Failed to connect to 127.0.0.1 port 32600: Connection refused)
Mar 29, 2021 20:23:50.376 [7268] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 29, 2021 20:23:50.376 [7268] WARN - HTTP error requesting GET http://127.0.0.1:32600/devices/dvb%23hdhr%230%23272019675 (7, Couldn’t connect to server) (Failed to connect to 127.0.0.1 port 32600: Connection refused)
Mar 29, 2021 20:23:50.379 [7268] ERROR - Error parsing content.
Mar 29, 2021 20:23:50.381 [7268] ERROR - Error parsing XML: Error parsing file.
Mar 29, 2021 20:23:50.391 [7268] ERROR - Grabber: Error obtaining device status.
Mar 29, 2021 20:23:50.537 [7268] ERROR - DVR:Device: Error refreshing existing device device://tv.plex.grabbers.tunerservice/dvb%23hdhr%231036B0DB, marking as dead.
Mar 29, 2021 20:23:50.736 [7813] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 65 items.
Mar 29, 2021 20:23:50.882 [7819] WARN - SLOW QUERY: It took 280.000000 ms to retrieve 66 items.
Mar 29, 2021 20:23:50.887 [7817] WARN - SLOW QUERY: It took 250.000000 ms to retrieve 88 items.
Mar 29, 2021 20:23:50.891 [7823] WARN - SLOW QUERY: It took 320.000000 ms to retrieve 79 items.
Mar 29, 2021 20:23:50.967 [7818] WARN - SLOW QUERY: It took 390.000000 ms to retrieve 66 items.
Mar 29, 2021 20:23:50.965 [7833] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 80 items.
Mar 29, 2021 20:23:51.005 [7830] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 67 items.
Mar 29, 2021 20:23:53.582 [7264] ERROR - NAT: PMP::getPublicIP, couldn’t initialize: -3:Unable to get Gateway
Mar 29, 2021 20:24:35.816 [7806] ERROR - XML: Entity: line 1:
Mar 29, 2021 20:24:35.816 [7806] ERROR - XML: parser
Mar 29, 2021 20:24:35.816 [7806] ERROR - XML: error :
Mar 29, 2021 20:24:35.816 [7806] ERROR - XML: Start tag expected, ‘<’ not found
Mar 29, 2021 20:24:35.816 [7806] ERROR - XML: status=ok
Mar 29, 2021 20:24:35.816 [7806] ERROR - XML: ^
Mar 29, 2021 20:24:35.816 [7806] ERROR - Error parsing content.
Mar 29, 2021 20:24:35.824 [7806] ERROR - Error parsing XML: Error parsing file.
Mar 29, 2021 20:24:35.824 [7806] ERROR - SSDP: Error parsing device schema for http://192.168.1.83:9080
Mar 29, 2021 20:25:10.283 [7806] ERROR - XML: Entity: line 1:
Mar 29, 2021 20:25:10.283 [7806] ERROR - XML: parser
Mar 29, 2021 20:25:10.283 [7806] ERROR - XML: error :
Mar 29, 2021 20:25:10.283 [7806] ERROR - XML: Start tag expected, ‘<’ not found
Mar 29, 2021 20:25:10.283 [7806] ERROR - XML: status=ok
Mar 29, 2021 20:25:10.283 [7806] ERROR - XML: ^
Mar 29, 2021 20:25:10.283 [7806] ERROR - Error parsing content.
Mar 29, 2021 20:25:10.293 [7806] ERROR - Error parsing XML: Error parsing file.
Mar 29, 2021 20:25:10.293 [7806] ERROR - SSDP: Error parsing device schema for http://192.168.1.83:9080
Mar 29, 2021 20:25:40.294 [7806] ERROR - XML: Entity: line 1:
Mar 29, 2021 20:25:40.294 [7806] ERROR - XML: parser
Mar 29, 2021 20:25:40.294 [7806] ERROR - XML: error :
Mar 29, 2021 20:25:40.294 [7806] ERROR - XML: Start tag expected, ‘<’ not found
Mar 29, 2021 20:25:40.294 [7806] ERROR - XML: status=ok
Mar 29, 2021 20:25:40.294 [7806] ERROR - XML: ^
Mar 29, 2021 20:25:40.294 [7806] ERROR - Error parsing content.
Mar 29, 2021 20:25:40.294 [7806] ERROR - Error parsing XML: Error parsing file.
Mar 29, 2021 20:25:40.294 [7806] ERROR - SSDP: Error parsing device schema for http://192.168.1.83:9080
Mar 29, 2021 20:26:11.025 [7806] ERROR - XML: Entity: line 1:
Mar 29, 2021 20:26:11.025 [7806] ERROR - XML: parser
Mar 29, 2021 20:26:11.025 [7806] ERROR - XML: error :
Mar 29, 2021 20:26:11.025 [7806] ERROR - XML: Start tag expected, ‘<’ not found
Mar 29, 2021 20:26:11.025 [7806] ERROR - XML: status=ok
Mar 29, 2021 20:26:11.025 [7806] ERROR - XML: ^
Mar 29, 2021 20:26:11.025 [7806] ERROR - Error parsing content.
Mar 29, 2021 20:26:11.025 [7806] ERROR - Error parsing XML: Error parsing file.
Mar 29, 2021 20:26:11.025 [7806] ERROR - SSDP: Error parsing device schema for http://192.168.1.83:9080
1 Like
I did do the update, but I guess I need to wait longer before allowing clients to connect. I just thought the database changes had already happened with updating to 4288. Do I need to optimize the database after updating again?
I have a similar problem since a few months ago… pleaaaaase help me, no one has replied to my posts
I have replied in your referenced thread. I’m not very good with windows at all but it looks like you either:
a. have permission problems
b. you aren’t getting file locking which is needed for the transcoder.
Mar 28, 2021 21:54:06.100 [19224] DEBUG - Jobs: 'F:\Program Files\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 15708 is -1059143458 (intentional termination)
Mar 28, 2021 21:54:06.110 [15108] DEBUG - Transcoder: Cleaning old transcode directories.
Mar 28, 2021 21:54:06.110 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-00iomqwsprwotoinpnag6ho2-96a67c3d-8b2a-46d5-97f4-f180eca17342"
Mar 28, 2021 21:54:06.123 [15108] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: El proceso no tiene acceso al archivo porque est\E1 siendo utilizado por otro proceso: "D:\Transcode\Sessions\plex-transcode-00iomqwsprwotoinpnag6ho2-96a67c3d-8b2a-46d5-97f4-f180eca17342\chunk-stream0-00534.m4s")
Mar 28, 2021 21:54:06.123 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-01rxpctq2capbj0abugmm48i-1a1a926b-e98c-4d47-8932-0adee857f955"
Mar 28, 2021 21:54:06.124 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-01rxpctq2capbj0abugmm48i-a761c701-c3b1-4347-8b4d-089ad7af7d0c"
Mar 28, 2021 21:54:06.131 [15108] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: El proceso no tiene acceso al archivo porque est\E1 siendo utilizado por otro proceso: "D:\Transcode\Sessions\plex-transcode-01rxpctq2capbj0abugmm48i-a761c701-c3b1-4347-8b4d-089ad7af7d0c\chunk-stream0-00343.m4s")
Mar 28, 2021 21:54:06.131 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-0qt2i0uall0scqvrn00vi152-6cb46f12-7702-4ac7-8db1-40377030a345"
Mar 28, 2021 21:54:06.132 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-0y1g9zdsevft1967h1lxztdm-f4bf768c-6a7d-485d-bec2-72a0826ced90"
Mar 28, 2021 21:54:06.139 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-6becj7zuynqhxjji9xmnhdaz-ee70d6fb-25a8-4c25-b76e-b9123deec4e4"
Mar 28, 2021 21:54:06.184 [15108] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: El proceso no tiene acceso al archivo porque est\E1 siendo utilizado por otro proceso: "D:\Transcode\Sessions\plex-transcode-6becj7zuynqhxjji9xmnhdaz-ee70d6fb-25a8-4c25-b76e-b9123deec4e4\sub-chunk-00007")
Mar 28, 2021 21:54:06.184 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-76301a923e2c04a8-com-plexapp-android-8229d4d4-66a6-476b-93ce-6f898efae520"
Mar 28, 2021 21:54:06.190 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-92ecdd3dba1375b4-com-plexapp-android-b7a59c78-96e9-494b-aee9-9860d3a6a5c0"
Mar 28, 2021 21:54:06.205 [15108] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: El proceso no tiene acceso al archivo porque est\E1 siendo utilizado por otro proceso: "D:\Transcode\Sessions\plex-transcode-92ecdd3dba1375b4-com-plexapp-android-b7a59c78-96e9-494b-aee9-9860d3a6a5c0\media-00117.ts")
Mar 28, 2021 21:54:06.205 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-db41k1ghobtgol0kc6pcv7he-56a09601-d990-4022-8228-5fcc1c716171"
Mar 28, 2021 21:54:06.254 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-f65hzjcs140jhgpu8heo17jf-9d8ef2cb-bad7-42ad-91b3-cf8f6000e909"
Mar 28, 2021 21:54:06.255 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-f65hzjcs140jhgpu8heo17jf-a8b29cb1-244b-4c1a-85ff-46b81ce1537e"
Mar 28, 2021 21:54:06.268 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-h29v2qfizjg4dtuqwvbzkv4x-445b175e-73a9-4e4f-9d9f-f1544cae096a"
Mar 28, 2021 21:54:06.281 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-jevpiyotyhlznb44l10chep5-a99a558a-2204-4186-a846-da94f7d4ddac"
Mar 28, 2021 21:54:06.293 [15108] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: El proceso no tiene acceso al archivo porque est\E1 siendo utilizado por otro proceso: "D:\Transcode\Sessions\plex-transcode-jevpiyotyhlznb44l10chep5-a99a558a-2204-4186-a846-da94f7d4ddac\chunk-00070")
Mar 28, 2021 21:54:06.293 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-2cc4fee7-841e-40d9-b0cd-a2e0b0d27eca"
Mar 28, 2021 21:54:06.293 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-498822e9-b01b-4119-8d0c-7768399a7b61"
Mar 28, 2021 21:54:06.304 [15108] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: El proceso no tiene acceso al archivo porque est\E1 siendo utilizado por otro proceso: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-498822e9-b01b-4119-8d0c-7768399a7b61\chunk-stream0-00106.m4s")
Mar 28, 2021 21:54:06.304 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-a16e9934-a42c-499b-9239-c4f8b8b65922"
Mar 28, 2021 21:54:06.308 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-b8c57934-1601-46b9-afa7-f3608f33dee5"
Mar 28, 2021 21:54:06.317 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-bf7b2a70-117e-4ac8-bc3e-0f4f1d932284"
Mar 28, 2021 21:54:06.320 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-ea032e5f-a5b4-4369-b20e-d64ae46ebcd9"
Mar 28, 2021 21:54:06.323 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-msg756vl6odtj7ibpg8j39mp-ef4998bb-1845-459b-b47e-2c43160db097"
Mar 28, 2021 21:54:06.324 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-oj2ow1iqu0tnvcxrevb7jqz8-2f4a5261-e136-4d20-bd5f-c7a1cc8ec16a"
Mar 28, 2021 21:54:06.327 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-rtzxhrsmllrhsphamf7myv5b-8ce55554-a492-4c2c-9c48-5befaa6e9c1c"
Mar 28, 2021 21:54:06.352 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-rzu0n2jw47h16kuc27qp4wvc-be3641bd-96f7-41ad-a684-d99916c6b831"
Mar 28, 2021 21:54:06.364 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-wh4w1crck3h11exodmjstsq1-842467fe-8792-4709-86e7-073f35ba757c"
Mar 28, 2021 21:54:06.377 [15108] DEBUG - Transcoder: Cleaning old transcode directory: "D:\Transcode\Sessions\plex-transcode-z2acp4l7a4erulj5yxafes6a-cd7219f4-9166-4d74-a605-989264faa671"
Mar 28, 2021 21:54:06.417 [15108] DEBUG - Whacked session rtzxhrsmllrhsphamf7myv5b, 0 remaining.
Mar 28, 2021 21:54:06.417 [15108] DEBUG - Killed 6 idle sessions out of a total of 6.
Again, thanks for your reply. You’re right, I’m not very good with Windows, that’s why I’m in this trouble 
How can I do to get file locking for the transcoder?
I am having this same issue where should I put my logs
I update about 10 minutes ago, my server only seems to take about 3 minutes before I start seeing SSDP messages in the logs etc which to me indicates she’s ready to go. This is when I open the flood gates back up - here’s hoping!
I recently started running PMS on a TerraMaster F5-422 and it wont stay running for more than an hour or so. I have uninstalled and reinstalled PMS and checked for any update to the NAS. Can someone help me figure out where the logs are so i can post them here?
Sure,
DEBUG logs, (VERBOSE OFF)
Post the ZIP.
With any luck, the residuals are still in the logs bufer.
I reverted back to Version 1.22.0.4163. Every update over the last 3-4 days has wreaked havoc on my server. Everything was fine until 4228, then 4256 fixed some things, but then upgraded to 4262… started crashing again, reverted back to 4256, and still kept crashing, so I gave up. It’s resetting everyone’s home screen, and other various issues. I’m not updating anymore until I read for at least 1-2 weeks in the forums that it’s solid.
2 Likes
Getting the same
XML: Entity: line 1:[SQUARE]
XML: parser[SQUARE]
XML: error :[SQUARE]
XML: Start tag expected, '<' not found[SQUARE]
XML: status=ok[SQUARE]
XML: ^[SQUARE]
Error parsing content.
Error parsing XML: Error parsing file.
SSDP: Error parsing device schema for http://192.168.X.XXX:9080
That others are talking about. I’m seeing it in the console not the log file, which may be the difference, but I’m noticing a character that isn’t being copied at the end of those lines. Anyone else noticing this?
I’m also seeing a lot of Warning messages like
Unable to load episode file ["seasons\1\episodes\1.xml"]
scattered throughout which I don’t usually see
Also seeing random
PlexClient::downloadContainer: expected MediaContainer element, found html
&
Subscription: No container available for /tv.plex.providers.epg.cloud:7/metadata/plex%3A%2F%XXXXXXXXXXXXXX
& my Windows App or my Chrome Tab will start using 1-3GB of RAM after anywhere from 10 minutes to an hour after I start them.
I’m not posting my logs as I don’t think it’s necessary since so many people are having the same issues, But I haven’t seen any note of the squares so I thought I’d mention that now that I’m allowed to post again after being blocked for pointing out how a mod was talking in circles & providing misleading information
I updated to the latest available beta version last night on Shield TV, thought I was going to be in the clear, it was working… went to bed, and woke up this morning and found PMS down again.
