Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Content type of propfind request not XML #276

Closed
wagnerand opened this issue Jan 27, 2013 · 7 comments
Closed

Content type of propfind request not XML #276

wagnerand opened this issue Jan 27, 2013 · 7 comments

Comments

@wagnerand
Copy link

Using ownCloud 4.5.6 and Mac client 1.2.0, syncing fails from time to time with the following error (1-2 times per hour avg.):

oc_module: Simple propfind result code 200.
oc_module: ERROR: Content type of propfind request not XML: text/html.
oc_module: WRN: propfind named failed with 5, request error: 200 OK

The previous and the next sync work fine. I didn't change any of the files neither before nor after that issue happened.

I can provide a complete mirall log if you need.
@danimo
Copy link
Contributor

danimo commented Jan 27, 2013

Please provide details about your server environment.

@wagnerand
Copy link
Author

What exactly do you need to know?

owncloud is running in a shared hosting environment on PHP 5.3.10 and MySQL 5.1.60.

@wagnerand
Copy link
Author

Any updates on this?

@danimo
Copy link
Contributor

danimo commented Feb 3, 2013

Please follow http://doc.owncloud.org/desktop/1.1/troubleshooting.html. Test with a real webdav client, the browser will not suffice. If that works, but ownCloud Client still doesn't, please fill in the template linked from https://github.com/owncloud/mirall/blob/master/CONTRIBUTING.md.

@wagnerand
Copy link
Author

Expected behaviour

Syncing works all the time.

Actual behaviour

Syncing fails from time to time.

Steps to reproduce

No special steps needed, I just added my folder to sync.

Server configuration

Operating system: Linux 2.6.32.4

Web server: Apache 2.2.22

Database: MySQL 5.1.60

PHP version: PHP 5.3.10

ownCloud version: 4.5.6

Client configuration

Client version: 1.2.0

Operating system: Mac 10.8.2

OS language: German

Installation path of client: /Applications/owncloud.app/

Logs

output of owncloud --logwindow or owncloud --logfile log.txt

01-26 02:51:36:293 Logging into logfile: "/Users/andreas/owncloud.log" with flush false
01-26 02:51:36:314 "################## ownCloud de_DE 1.2.0 "
01-26 02:51:36:381 Network Location: ""
01-26 02:51:36:405 Get Request to "status.php"
01-26 02:51:36:406 Returning configured owncloud url: "https://mydomain.com/owncloud/"
01-26 02:51:36:406 Returning configured owncloud url: "https://mydomain.com/owncloud/"
01-26 02:51:36:406 Setting up host header: "mydomain.com"
01-26 02:51:36:594 status.php returns: "{"installed":"true","version":"4.90.7","versionstring":"4.5.6","edition":""}" 0 Reply: QNetworkReplyImpl(0x105a103c0)
01-26 02:51:36:594 Unknown info from ownCloud status.php: "installed" = "true"
01-26 02:51:36:594 ** Application: ownCloud found: "https://mydomain.com/owncloud" with version "4.5.6" ( "4.90.7" )
01-26 02:51:36:594 ** Setting ownCloud Server version to "4.90.7"
01-26 02:51:36:600 Returning configured owncloud url: "https://mydomain.com/owncloud/"
01-26 02:51:36:624 Credentials successfully fetched: true
01-26 02:51:36:664 # checking for authentication settings.
01-26 02:51:36:664 Get Request to "/"
01-26 02:51:36:664 Returning configured owncloud url: "https://mydomain.com/owncloud/remote.php/webdav/"
01-26 02:51:36:664 Returning configured owncloud url: "https://mydomain.com/owncloud/"
01-26 02:51:36:664 Setting up host header: "mydomain.com"
01-26 02:51:36:914 ######## Credentials are ok!
01-26 02:51:36:914 * Setup folders from "/Users/andreas/Library/Application Support/ownCloud/folders"
01-26 02:51:36:914 ` -> setting up: "ownCloud"
01-26 02:51:36:914 -> file path: "/Users/andreas/Library/Application Support/ownCloud/folders/ownCloud"
01-26 02:51:36:914 Returning configured owncloud url: "https://mydomain.com/owncloud/remote.php/webdav/"
01-26 02:51:36:914 setting remote poll timer interval to 28028 msec for folder "ownCloud"
01-26 02:51:36:914 FolderWatcherPrivate::startWatching() "/Users/andreas/ownCloud"
01-26 02:51:36:915 * Pending events for "/Users/andreas/ownCloud" will be processed after events stop for 1000 milliseconds ( "03:08:16" ). 0 events until now )
01-26 02:51:36:915 ==> returning exclude file path: "/Applications/owncloud.app/Contents/Resources/sync-exclude.lst"
01-26 02:51:36:915 Checked local path ok
01-26 02:51:36:915 ****** ownCloud folder using watcher *******
01-26 02:51:36:915 Adding folder to Folder Map Mirall::ownCloudFolder(0x105bc5480)
01-26 02:51:36:915 Folder in overallStatus Message: Mirall::ownCloudFolder(0x105bc5480) with name "ownCloud"
01-26 02:51:36:916 Sync state changed for folder "ownCloud" : "Not yet Started"
01-26 02:51:37:007 Folder: Mirall::ownCloudFolder(0x105bc5480)
01-26 02:51:37:007 ==> load folder icon "owncloud-framed"
01-26 02:51:37:007 Returning configured owncloud url: "https://mydomain.com/owncloud/remote.php/webdav/"
01-26 02:51:37:008 Folder in overallStatus Message: Mirall::ownCloudFolder(0x105bc5480) with name "ownCloud"
01-26 02:51:37:916 * Processing of event queue for "/Users/andreas/ownCloud"
01-26 02:51:37:916 * Notify 0 change items for "/Users/andreas/ownCloud"
01-26 02:51:37:916 ** Changed was notified on ()
01-26 02:51:37:916 * "ownCloud" Poll timer disabled
01-26 02:51:37:916 Schedule folder "ownCloud" to sync!
01-26 02:51:37:916 XX slotScheduleFolderSync: folderQueue size: 1
01-26 02:51:37:916 ==> load folder icon "owncloud-framed"
01-26 02:51:37:917 Returning configured owncloud url: "https://mydomain.com/owncloud/remote.php/webdav/"
01-26 02:51:37:917 Folder in overallStatus Message: Mirall::ownCloudFolder(0x105bc5480) with name "ownCloud"
01-26 02:51:37:918 Sync state changed for folder "ownCloud" : "Sync Running"
01-26 02:51:37:918 *** Start syncing url to ownCloud: "ownclouds://mydomain.com/owncloud/remote.php/webdav/clientsync"
01-26 02:51:37:918 Returning configured owncloud url: "https://mydomain.com/owncloud/"
01-26 02:51:37:918 starting to sync QThread(0x1015154e0) QThread(0x1022b7660)
01-26 02:51:37:919 ==> returning exclude file path: "/Applications/owncloud.app/Contents/Resources/sync-exclude.lst"
01-26 02:51:37:919 ==== added CSync exclude List: "/Applications/owncloud.app/Contents/Resources/sync-exclude.lst"

...

01-26 13:04:55:923 * Polling "ownCloud" for changes. Ignoring all pending events until now
01-26 13:04:55:923 * "ownCloud" Poll timer disabled
01-26 13:04:55:923 Schedule folder "ownCloud" to sync!
01-26 13:04:55:923 XX slotScheduleFolderSync: folderQueue size: 1
01-26 13:04:55:923 ==> load folder icon "owncloud-framed"
01-26 13:04:55:924 Returning configured owncloud url: "https://mydomain.com/owncloud/remote.php/webdav/"
01-26 13:04:55:924 Folder in overallStatus Message: Mirall::ownCloudFolder(0x105bc5480) with name "ownCloud"
01-26 13:04:55:925 Sync state changed for folder "ownCloud" : "Sync Running"
01-26 13:04:55:925 *** Start syncing url to ownCloud: "ownclouds://mydomain.com/owncloud/remote.php/webdav/clientsync"
01-26 13:04:55:925 Returning configured owncloud url: "https://mydomain.com/owncloud/"
01-26 13:04:55:925 starting to sync QThread(0x1015154e0) QThread(0x105a0c290)
01-26 13:04:55:926 * csync thread started
01-26 13:04:55:926 * event notification disabled
01-26 13:04:55:926 >===================================== sync started for "ownCloud"
01-26 13:04:55:926 ==> returning exclude file path: "/Applications/owncloud.app/Contents/Resources/sync-exclude.lst"
01-26 13:04:55:926 ==== added CSync exclude List: "/Applications/owncloud.app/Contents/Resources/sync-exclude.lst"
01-26 13:04:55:931 #### Update start #################################################### >>
01-26 13:04:55:931 csync_ftw: Incoming read_from_db-Flag for /Users/andreas/ownCloud: 0
01-26 13:04:55:931 csync_ftw: .csync_journal.db excluded
01-26 13:04:55:931 csync_ftw: .csync_journal.db.ctmp excluded
01-26 13:04:55:931 csync_ftw: .DS_Store excluded

...

01-26 13:04:55:937 csync_update: Update detection for local replica took 0,01 seconds walking 18 files.
01-26 13:04:55:937 csync_ftw: Incoming read_from_db-Flag for ownclouds://mydomain.com/owncloud/remote.php/webdav/clientsync: 0
01-26 13:04:55:937 csync_ftw: Checking for read from db for ownclouds://mydomain.com/owncloud/remote.php/webdav/clientsync: 0
01-26 13:04:55:937 oc_module: opendir method called on ownclouds://mydomain.com/owncloud/remote.php/webdav/clientsync
01-26 13:04:55:937 oc_module: * scheme ownclouds
01-26 13:04:55:937 oc_module: * host mydomain.com
01-26 13:04:55:937 oc_module: * port 0
01-26 13:04:55:938 oc_module: * path /owncloud/remote.php/webdav/clientsync
01-26 13:04:55:938 oc_module: * user
01-26 13:04:55:938 oc_module: ne_sock_init: 0
01-26 13:04:55:938 oc_module: No proxy configured.
01-26 13:04:55:939 FolderWatcherPrivate::callback by OS X
01-26 13:04:55:939 FolderWatcher::changeDetected when eventsEnabled() -> ignore
01-26 13:04:56:024 oc_module: Call the csync callback for SSL problems
01-26 13:04:56:024 SSL Fingerprint from neon: "08:5e:bc:d9:bd:eb:4b:ee:18:97:a3:1d:4b:9d:f4:2c:1a:bf:4a:5c" compared to verified: "08:5e:bc:d9:bd:eb:4b:ee:18:97:a3:1d:4b:9d:f4:2c:1a:bf:4a:5c"
01-26 13:04:56:024 oc_module: ## VERIFY_SSL CERT: 0
01-26 13:04:56:112 oc_module: Set-Cookie found: 50846b6dc9691=f309f44315c3a1ac77bec59558d9822f; path=/; HttpOnly
01-26 13:04:56:112 oc_module: ----> Session-key: 50846b6dc9691=f309f44315c3a1ac77bec59558d9822f
01-26 13:04:56:116 oc_module: Authentication required
01-26 13:04:56:116 oc_module: Call the csync callback for ownCloud
01-26 13:04:56:215 oc_module: Set-Cookie found: 50846b6dc9691=35e1d87bb96abe5962f9edc1d5f1b3dc; path=/; HttpOnly
01-26 13:04:56:215 oc_module: ----> Session-key: 50846b6dc9691=35e1d87bb96abe5962f9edc1d5f1b3dc
01-26 13:04:56:307 oc_module: Simple propfind result code 200.
01-26 13:04:56:307 oc_module: ERROR: Content type of propfind request not XML: text/html.
01-26 13:04:56:307 oc_module: WRN: propfind named failed with 5, request error: 200 OK
01-26 13:04:56:307 oc_module: Errno set to 10011
01-26 13:04:56:308 csync_ftw: opendir failed for ownclouds://mydomain.com/owncloud/remote.php/webdav/clientsync - Unknown error: 10011 (errno 10011)
01-26 13:04:56:308 csync_update: Update detection for remote replica took 0,37 seconds walking 0 files.
01-26 13:04:56:308 #### ERROR csync_update: "CSync konnte eine bestimmte Datei nicht finden.
Systemnachricht:Server error: PROPFIND reply is not XML formatted!"
01-26 13:04:56:308 csync_lock_remove: Removing lock file: /Users/andreas/Library/Application Support/ownCloud//lock
01-26 13:04:56:309 CSync run took 383 Milliseconds
01-26 13:04:56:309 -> CSync Finished slot with error true
01-26 13:04:56:309 ** error Strings: ("CSync konnte eine bestimmte Datei nicht finden.
Systemnachricht:Server error: PROPFIND reply is not XML formatted!")
01-26 13:04:56:309 * owncloud csync thread finished with error
01-26 13:04:56:309 Starting Event logging again in 2000 milliseconds
01-26 13:04:56:309 OO folder slotSyncFinished: result: 4 local: false
01-26 13:04:56:309 ==> load folder icon "owncloud-framed"
01-26 13:04:56:309 Returning configured owncloud url: "https://mydomain.com/owncloud/remote.php/webdav/"
01-26 13:04:56:310 Folder in overallStatus Message: Mirall::ownCloudFolder(0x105bc5480) with name "ownCloud"
01-26 13:04:56:311 Sync state changed for folder "ownCloud" : "Error"
01-26 13:04:56:311 * "ownCloud" Poll timer enabled with 28028 milliseconds
01-26 13:04:56:311 <===================================== sync finished for "ownCloud"
01-26 13:04:56:319 FolderWatcherPrivate::callback by OS X
01-26 13:04:56:319 FolderWatcher::changeDetected when eventsEnabled() -> ignore
01-26 13:04:56:511 XX slotScheduleFolderSync: folderQueue size: 0
01-26 13:04:58:309 * event notification enabled

Web server error log

I don't have access to that log file.

ownCloud log (data/owncloud.log)

{"app":"core","message":"Unable to parse knowledgebase content","level":4,"time":1350855559}
{"app":"core","message":"Unable to parse knowledgebase content","level":4,"time":1352051997}
{"app":"core","message":"Unable to parse knowledgebase content","level":4,"time":1352053805}
{"app":"core","message":"Unable to parse knowledgebase content","level":4,"time":1357262251}
{"app":"core","message":"Unable to parse knowledgebase content","level":4,"time":1359060761}

(Note: This is intended because I disabled "allow_url_fopen")

@wagnerand
Copy link
Author

Any updates on this?

@dragotin
Copy link
Contributor

Please see the discussion on #285 - the cause of this problem is still not found but a workaround is there.

I am closing this one as a dupe.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@wagnerand @dragotin @danimo