426655 - Openssl is sometimes not able to decrypt data on the server

Status: VERIFIED DUPLICATE of bug 425859

(Cloud Services :: General, defect)

Description

[Al Billings [:abillings - ex-MoCo]]

Whenever I synch Weave, with either 0.1.28 or 0.1.29 (now that I've upgraded), bookmarks synch fine but history fails with an error, "Async.Generator	ERROR	Exception caught in WeaveLockWrapper: openssl did not run successfully, error code 1".

Below is the verbose log:

2008-04-02 17:19:49	Service.DAV	DEBUG	LOCK request for 
2008-04-02 17:19:49	Service.DAV	DEBUG	GET request for meta/version
2008-04-02 17:19:49	Service.DAV	DEBUG	GET request for private/privkey
2008-04-02 17:19:49	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl rsa -in key -pubout -out output -passin file:pass
2008-04-02 17:19:49	Service.BmkEngine	INFO	Beginning sync
2008-04-02 17:19:49	Service.DAV	DEBUG	GET request for user-data/
2008-04-02 17:19:49	Service.DAV	DEBUG	GET request for user-data/bookmarks/
2008-04-02 17:19:49	Service.BmkEngine	DEBUG	Getting status file from server
2008-04-02 17:19:49	Service.DAV	DEBUG	GET request for user-data/bookmarks/status.json
2008-04-02 17:19:49	Service.BmkEngine	INFO	Got status file from server
2008-04-02 17:19:49	Service.DAV	DEBUG	GET request for user-data/bookmarks/keys.json
2008-04-02 17:19:49	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl base64 -d -in input -out tmp-output
2008-04-02 17:19:49	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl rsa -in key -out tmp-key -passin file:pass
2008-04-02 17:19:49	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl rsautl -decrypt -inkey tmp-key -in tmp-output -out output
2008-04-02 17:19:49	Service.BmkEngine	INFO	Downloading server deltas
2008-04-02 17:19:49	Service.DAV	DEBUG	GET request for user-data/bookmarks/deltas.json
2008-04-02 17:19:49	Service.Crypto	DEBUG	Decrypting data
2008-04-02 17:19:49	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl aes-256-cbc -d -a -salt -in input -out output -pass file:pass
2008-04-02 17:19:50	Service.Crypto	DEBUG	Done decrypting data
2008-04-02 17:19:50	Service.BmkEngine	INFO	Local snapshot version: 17
2008-04-02 17:19:50	Service.BmkEngine	INFO	Server status: 0
2008-04-02 17:19:50	Service.BmkEngine	INFO	Server maxVersion: 17
2008-04-02 17:19:50	Service.BmkEngine	INFO	Server snapVersion: 0
2008-04-02 17:19:51	Service.BmkEngine	INFO	Sync complete: no changes needed on client or server
2008-04-02 17:19:51	Service.HistEngine	INFO	Beginning sync
2008-04-02 17:19:51	Service.DAV	DEBUG	GET request for user-data/
2008-04-02 17:19:51	Service.DAV	DEBUG	GET request for user-data/history/
2008-04-02 17:19:51	Service.HistEngine	DEBUG	Getting status file from server
2008-04-02 17:19:51	Service.DAV	DEBUG	GET request for user-data/history/status.json
2008-04-02 17:19:51	Service.HistEngine	INFO	Got status file from server
2008-04-02 17:19:51	Service.DAV	DEBUG	GET request for user-data/history/keys.json
2008-04-02 17:19:51	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl base64 -d -in input -out tmp-output
2008-04-02 17:19:51	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl rsa -in key -out tmp-key -passin file:pass
2008-04-02 17:19:51	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl rsautl -decrypt -inkey tmp-key -in tmp-output -out output
2008-04-02 17:19:51	Service.HistEngine	INFO	Downloading server deltas
2008-04-02 17:19:51	Service.DAV	DEBUG	GET request for user-data/history/deltas.json
2008-04-02 17:19:51	Service.Crypto	DEBUG	Decrypting data
2008-04-02 17:19:51	Service.Util	DEBUG	Running command: /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/abillings/Library/Application Support/Firefox/Profiles/grzcadgw.Default User/weave/tmp openssl aes-256-cbc -d -a -salt -in input -out output -pass file:pass
2008-04-02 17:19:51	Service.DAV	DEBUG	UNLOCK request for 
2008-04-02 17:19:51	Async.Generator	ERROR	Exception caught in WeaveLockWrapper: openssl did not run successfully, error code 1
2008-04-02 17:19:51	Async.Generator	DEBUG	Stack trace:
JS frame :: chrome://browser/content/browser.xul :: oncommand :: line 1
JS frame :: chrome://weave/content/sync.js :: Sync_doSync :: line 260
JS frame :: file:///Users/abillings/Library/Application%20Support/Firefox/Profiles/grzcadgw.Default%20User/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/service.js :: WeaveSync_sync :: line 498
JS frame :: unknown (async) :: WeaveLockWrapper






JS frame :: file:///Users/abillings/Library/Application%20Support/Firefox/Profiles/grzcadgw.Default%20User/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/util.js :: EL_notify :: line 354
JS frame :: file:///Users/abillings/Library/Application%20Support/Firefox/Profiles/grzcadgw.Default%20User/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: anonymous :: line 214
JS frame :: file:///Users/abillings/Library/Application%20Support/Firefox/Profiles/grzcadgw.Default%20User/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: AsyncGen__done :: line 237
JS frame :: file:///Users/abillings/Library/Application%20Support/Firefox/Profiles/grzcadgw.Default%20User/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: anonymous :: line 103
JS frame :: file:///Users/abillings/Library/Application%20Support/Firefox/Profiles/grzcadgw.Default%20User/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: AsyncGen_cont :: line 188
JS frame :: file:///Users/abillings/Library/Application%20Support/Firefox/Profiles/grzcadgw.Default%20User/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/engines.js :: BmkEngine__getServerData :: line 547
JS frame :: unknown (async) :: Crypto_PBEdecrypt

Comment 1

[Sigurd Foshaug]

Getting exactly the same error here on Weave 0.1.29 running on Mozilla/5.0 (Macintosh; U; Intel Mac OS X 10.5; en-US; rv:1.9pre) Gecko/2008041104 Minefield/3.0pre ID:2008041104

Verbose log:


2008-04-12 22:46:56	Service.DAV	DEBUG	LOCK request for 
2008-04-12 22:46:59	Service.DAV	DEBUG	GET request for meta/version
2008-04-12 22:47:00	Service.DAV	DEBUG	GET request for private/privkey
2008-04-12 22:47:02	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl rsa -in key -pubout -out output -passin file:pass
2008-04-12 22:47:02	Service.BmkEngine	INFO	Beginning sync
2008-04-12 22:47:02	Service.DAV	DEBUG	GET request for user-data/
2008-04-12 22:47:03	Service.DAV	DEBUG	GET request for user-data/bookmarks/
2008-04-12 22:47:05	Service.BmkEngine	DEBUG	Getting status file from server
2008-04-12 22:47:05	Service.DAV	DEBUG	GET request for user-data/bookmarks/status.json
2008-04-12 22:47:07	Service.BmkEngine	INFO	Got status file from server
2008-04-12 22:47:07	Service.DAV	DEBUG	GET request for user-data/bookmarks/keys.json
2008-04-12 22:47:07	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl base64 -d -in input -out tmp-output
2008-04-12 22:47:07	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl rsa -in key -out tmp-key -passin file:pass
2008-04-12 22:47:07	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl rsautl -decrypt -inkey tmp-key -in tmp-output -out output
2008-04-12 22:47:07	Service.BmkEngine	INFO	Downloading server deltas
2008-04-12 22:47:07	Service.DAV	DEBUG	GET request for user-data/bookmarks/deltas.json
2008-04-12 22:47:13	Service.Crypto	DEBUG	Decrypting data
2008-04-12 22:47:13	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl aes-256-cbc -d -a -salt -in input -out output -pass file:pass
2008-04-12 22:47:13	Service.Crypto	DEBUG	Done decrypting data
2008-04-12 22:47:15	Service.BmkEngine	INFO	Local snapshot version: 35
2008-04-12 22:47:15	Service.BmkEngine	INFO	Server status: 0
2008-04-12 22:47:15	Service.BmkEngine	INFO	Server maxVersion: 35
2008-04-12 22:47:15	Service.BmkEngine	INFO	Server snapVersion: 0
2008-04-12 22:47:17	Service.BmkEngine	INFO	Sync complete: no changes needed on client or server
2008-04-12 22:47:17	Service.HistEngine	INFO	Beginning sync
2008-04-12 22:47:17	Service.DAV	DEBUG	GET request for user-data/
2008-04-12 22:47:18	Service.DAV	DEBUG	GET request for user-data/history/
2008-04-12 22:47:19	Service.HistEngine	DEBUG	Getting status file from server
2008-04-12 22:47:19	Service.DAV	DEBUG	GET request for user-data/history/status.json
2008-04-12 22:47:20	Service.HistEngine	INFO	Got status file from server
2008-04-12 22:47:20	Service.DAV	DEBUG	GET request for user-data/history/keys.json
2008-04-12 22:47:21	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl base64 -d -in input -out tmp-output
2008-04-12 22:47:21	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl rsa -in key -out tmp-key -passin file:pass
2008-04-12 22:47:21	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl rsautl -decrypt -inkey tmp-key -in tmp-output -out output
2008-04-12 22:47:21	Service.HistEngine	INFO	Downloading server deltas
2008-04-12 22:47:21	Service.DAV	DEBUG	GET request for user-data/history/deltas.json
2008-04-12 22:47:39	Service.Crypto	DEBUG	Decrypting data
2008-04-12 22:47:39	Service.Util	DEBUG	Running command: /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/extensions/{340c2bbc-ce74-4362-90b5-7c26312808ef}/openssl/unix/exec.sh /Users/gusto/Library/Application Support/Firefox/Profiles/n2bhjppu.default/weave/tmp openssl aes-256-cbc -d -a -salt -in input -out output -pass file:pass
2008-04-12 22:47:39	Service.DAV	DEBUG	UNLOCK request for 
2008-04-12 22:47:40	Async.Generator	ERROR	Exception caught in WeaveLockWrapper: openssl did not run successfully, error code 1
2008-04-12 22:47:40	Async.Generator	DEBUG	Stack trace:
JS frame :: chrome://browser/content/browser.xul :: oncommand :: line 1
JS frame :: chrome://weave/content/sync.js :: Sync_doSync :: line 260
JS frame :: file:///Users/gusto/Library/Application%20Support/Firefox/Profiles/n2bhjppu.default/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/service.js :: WeaveSync_sync :: line 498
JS frame :: unknown (async) :: WeaveLockWrapper






JS frame :: file:///Users/gusto/Library/Application%20Support/Firefox/Profiles/n2bhjppu.default/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/util.js :: EL_notify :: line 354
JS frame :: file:///Users/gusto/Library/Application%20Support/Firefox/Profiles/n2bhjppu.default/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: anonymous :: line 214
JS frame :: file:///Users/gusto/Library/Application%20Support/Firefox/Profiles/n2bhjppu.default/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: AsyncGen__done :: line 237
JS frame :: file:///Users/gusto/Library/Application%20Support/Firefox/Profiles/n2bhjppu.default/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: anonymous :: line 103
JS frame :: file:///Users/gusto/Library/Application%20Support/Firefox/Profiles/n2bhjppu.default/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/async.js :: AsyncGen_cont :: line 188
JS frame :: file:///Users/gusto/Library/Application%20Support/Firefox/Profiles/n2bhjppu.default/extensions/%7B340c2bbc-ce74-4362-90b5-7c26312808ef%7D/modules/engines.js :: BmkEngine__getServerData :: line 564
JS frame :: unknown (async) :: Crypto_PBEdecrypt

Comment 2

[Evan Prodromou]

I'm getting the same error. Weave 0.1.29, FF 3.0b5, Ubuntu "hardy".

Comment 3

[Chris Jones]

Same problem in Vista -- tried in Ultimate and Home Premium, same issue with both.  I tested on a fresh installation of Vista with no other apps, a fresh installation of FF3B5 with no other extensions, and got the error.  It retrieved all of the bookmarks from the server, but still ended up failing.  I haven't determined whether subsequent efforts to sync that end with the error are actually still getting data uploaded and downloaded even though it produces the error.  

The common ground on the Vista installations is that I'm running on a limited account rather than with admin privileges for better security.  I will try later to install on the admin account and see what happens.