Uncaught Error: Access to undeclared static property: OC\Files\Filesystem::$normalizedPathCache

[alphamat]

Operating system: Debian 8
Web server: Apache 2.4
Database: mysql 5.5.49-0+deb8u1
PHP version: 7.02
Nextcloud version: 10 / 9.1.0.16 - was also in 9.0.53

Uncaught Error: Access to undeclared static property: OC\Files\Filesystem::$normalizedPathCache in /var/www/htdocs/lib/private/Files/Filesystem.php:773 Stack trace: #0 /var/www/htdocs/lib/private/Files/View.php(2034): OC\Files\Filesystem::normalizePath('/MaT/files/Musi...') #1 /var/www/htdocs/lib/private/Files/View.php(1152): OC\Files\View->unlockFile('/Music - World/...', 1) #2 [internal function]: OC\Files\View->OC\Files\{closure}() #3 /var/www/htdocs/3rdparty/icewind/streams/src/CallbackWrapper.php(109): call_user_func(Object(Closure)) #4 [internal function]: Icewind\Streams\CallbackWrapper->stream_close() #5 {main} thrown at /var/www/htdocs/lib/private/Files/Filesystem.php#773

This happens with the audioplayer app from Rello -> https://github.com/Rello/audioplayer

He told me it is an core related issue and not with the app - he cannot resolve it

I found the issue now also when i click normal songs in folders switch from one to the other by short click

[alphamat]

in OC is the same issue - owncloud/core#22370
i think they find a way to reproduce it/find more info about

i hope this can help NC too to resolve this issue

[blizzz]

cc @icewind1991

[milgner]

Encountering the same issue without any additional apps, only the files app and the Windows client. The file is shared by [user] to [another user].

Sep 01 17:51:39 Nextcloud[54349]: {PHP} fopen(/var/storage/nextcloud/[user]/files/[path]): failed to open stream: No such file or directory at /var/www/nextcloud/lib/private/files/storage/local.php#261
Sep 01 17:51:39 Nextcloud[54349]: {webdav} Exception: {"Message":"HTTP\/1.1 503 Could not open file","Exception":"Sabre\\DAV\\Exception\\ServiceUnavailable","Code":0,"Trace":"#0 \/var\/www\/nextcloud\/3rdparty\/sabre\/dav\/lib\/DAV\/CorePlugin.php(83): OCA\\DAV\\Connector\\Sabre\\File->get()\n#1 [internal function]: Sabre\\DAV\\CorePlugin->httpGet(Object(Sabre\\HTTP\\Request), Object(Sabre\\HTTP\\Response))\n#2 \/var\/www\/nextcloud\/3rdparty\/sabre\/event\/lib\/EventEmitterTrait.php(105): call_user_func_array(Array, Array)\n#3 \/var\/www\/nextcloud\/3rdparty\/sabre\/dav\/lib\/DAV\/Server.php(459): Sabre\\Event\\EventEmitter->emit('method:GET', Array)\n#4 \/var\/www\/nextcloud\/3rdparty\/sabre\/dav\/lib\/DAV\/Server.php(248): Sabre\\DAV\\Server->invokeMethod(Object(Sabre\\HTTP\\Request), Object(Sabre\\HTTP\\Response))\n#5 \/var\/www\/nextcloud\/apps\/dav\/appinfo\/v1\/webdav.php(55): Sabre\\DAV\\Server->exec()\n#6 \/var\/www\/nextcloud\/remote.php(138): require_once('\/var\/www\/nextcl...')\n#7 {main}","File":"\/var\/www\/nextcloud\/apps\/dav\/lib\/connector\/sabre\/file.php","Line":306,"User":"[another_user]"}
Sep 01 17:51:39 Nextcloud[50767]: {PHP} fopen(/var/storage/nextcloud/[user]/files/[path]): failed to open stream: No such file or directory at /var/www/nextcloud/lib/private/files/storage/local.php#261
Sep 01 17:51:39 Nextcloud[50767]: {webdav} Exception: {"Message":"HTTP\/1.1 503 Could not open file","Exception":"Sabre\\DAV\\Exception\\ServiceUnavailable","Code":0,"Trace":"#0 \/var\/www\/nextcloud\/3rdparty\/sabre\/dav\/lib\/DAV\/CorePlugin.php(83): OCA\\DAV\\Connector\\Sabre\\File->get()\n#1 [internal function]: Sabre\\DAV\\CorePlugin->httpGet(Object(Sabre\\HTTP\\Request), Object(Sabre\\HTTP\\Response))\n#2 \/var\/www\/nextcloud\/3rdparty\/sabre\/event\/lib\/EventEmitterTrait.php(105): call_user_func_array(Array, Array)\n#3 \/var\/www\/nextcloud\/3rdparty\/sabre\/dav\/lib\/DAV\/Server.php(459): Sabre\\Event\\EventEmitter->emit('method:GET', Array)\n#4 \/var\/www\/nextcloud\/3rdparty\/sabre\/dav\/lib\/DAV\/Server.php(248): Sabre\\DAV\\Server->invokeMethod(Object(Sabre\\HTTP\\Request), Object(Sabre\\HTTP\\Response))\n#5 \/var\/www\/nextcloud\/apps\/dav\/appinfo\/v1\/webdav.php(55): Sabre\\DAV\\Server->exec()\n#6 \/var\/www\/nextcloud\/remote.php(138): require_once('\/var\/www\/nextcl...')\n#7 {main}","File":"\/var\/www\/nextcloud\/apps\/dav\/lib\/connector\/sabre\/file.php","Line":306,"User":"[another_user]"}
Sep 01 17:51:47 php-fpm7.0[52286]: ool www[52286]: {PHP} Uncaught Error: Access to undeclared static property: OC\Files\Filesystem::$normalizedPathCache in /var/www/nextcloud/lib/private/files/filesystem.php:798

It seems a bit strange that there seem to be so many PIDs reporting the problem with the first message being repeated.

[milgner]

More details on this issue: it looks like the server is trying to access a file that is supposed to be in the data folder of the share owner but somehow (in the filesystem) wound up in the data folders of one of the users the folder is shared with. Then, when trying to read the file from the filesystem it crashes with the above error. The database looks correct but the files in the data folder are all in different directories from where they are supposed to be.

Is there any way to easily find out which files are affected and move them back to the share owners data directory? Any information I could provide to help debugging this?

[milgner]

Here is what I think happened (as relevant for the server): due to user problem or a client bug, some shared folders were deleted. Afterwards we restored the folders using the trashbin view. Apparently the files were not restored into the data directory of the share owner but into the data directory of the share recipient.

Not sure if that makes sense to you, but, as I mentioned, feel free to ask me for further information if it can help you to debug it.

[MorrisJobke]

I also found this in our logs:

Uncaught Error: Access to undeclared static property: OC\\Files\\Filesystem::$normalizedPathCache in lib/private/Files/Filesystem.php:773
Stack trace:
#0 lib/private/Files/View.php(2034): OC\\Files\\Filesystem::normalizePath('/icewind/files/...')
#1 lib/private/Files/View.php(1152): OC\\Files\\View->unlockFile('/Photos/San Fra...', 1)
#2 [internal function]: OC\\Files\\View->OC\\Files\\{closure}()
#3 3rdparty/icewind/streams/src/CallbackWrapper.php(109): call_user_func(Object(Closure))
#4 [internal function]: Icewind\\Streams\\CallbackWrapper->stream_close()
#5 {main}
thrown at lib/private/Files/Filesystem.php#773

This was a PROPFIND against remote.php/webdav by @rullzer

[MorrisJobke]

It also only happened once in the past 30 days. Looks like a race condition 😢

[icewind1991]

Afaik this is a race condition in php's garbage collection on scrim exit, if we have a CallbackWrapper stream that is garbage collected after Filesystem::$normalizedPathCache has been gc'd

The solution to this is making sure we close streams before exiting the script, this used to be fairly consistent with webdav but was fixed by closing the output stream in sabre/dav

[MorrisJobke]

@icewind1991 Please check out the issue at owncloud. There are some reproduction steps.

[MorrisJobke]

Ping @icewind1991

[burningjoe]

I encountered the same problem while copying files to nextcloud via the builtin webdav-client in Mac OS Sierra. It was a batch of 300 pictures and it slowed down the process tremendously. Seems like it happened on almost every file.

Maybe you can find anything useful in there.

This is from nextcloud.log
Uncaught Error: Access to undeclared static property: OC\\Files\\Filesystem::$normalizedPathCache in \/var\/www\/nextcloud\/lib\/private\/Files\/Filesystem.php:773\nStack trace:\n#0 \/var\/www\/nextcloud\/lib\/private\/Files\/View.php(2034): OC\\Files\\Filesystem::normalizePath('\/jonathan\/files...')\n#1 \/var\/www\/nextcloud\/lib\/private\/Files\/View.php(1152): OC\\Files\\View->unlockFile('\/Photos\/Fotos2b...', 1)\n#2 [internal function]: OC\\Files\\View->OC\\Files\\{closure}()\n#3 \/var\/www\/nextcloud\/3rdparty\/icewind\/streams\/src\/CallbackWrapper.php(109): call_user_func(Object(Closure))\n#4 [internal function]: Icewind\\Streams\\CallbackWrapper->stream_close()\n#5 {main}\n thrown at \/var\/www\/nextcloud\/lib\/private\/Files\/Filesystem.php#773","level":3,"time":"2016-11-08T14:40:29+00:00","method":"PUT","url":"\/remote.php\/webdav\/Photos\/Fotos2b\/02%20Wandertag\/Besuch%20bei%20Josef%20(21).JPG","user":"jonathan"}

And from Apache:
[Tue Nov 08 16:04:39.766925 2016] [:error] [pid 1611] [client 192.168.1.2:50756] PHP Fatal error: Uncaught Error: Access to undeclared static property: OC\\Files\\Filesystem::$normalizedPathCache in /var/www/nextcloud/lib/private/Files/Filesystem.php:773\nStack trace:\n#0 /var/www/nextcloud/lib/private/Files/View.php(2034): OC\\Files\\Filesystem::normalizePath('/jonathan/files...')\n#1 /var/www/nextcloud/lib/private/Files/View.php(1152): OC\\Files\\View->unlockFile('/Photos/Fotos2b...', 1)\n#2 [internal function]: OC\\Files\\View->OC\\Files\\{closure}()\n#3 /var/www/nextcloud/3rdparty/icewind/streams/src/CallbackWrapper.php(109): call_user_func(Object(Closure))\n#4 [internal function]: Icewind\\Streams\\CallbackWrapper->stream_close()\n#5 {main}\n thrown in /var/www/nextcloud/lib/private/Files/Filesystem.php on line 773