Issue 203721 - chromium - Possible browser crash during suite_Smoke/desktopui_ScreenLocker - An open-source project to help move the web forward.

Issue 203721:	Possible browser crash during suite_Smoke/desktopui_ScreenLocker
	3 people starred this issue and may be notified of changes.	Back to list

Status:	Verified
Moved from:	issue chromium-os:20641
Owner:	nkostylev@chromium.org
Closed:	Sep 2011
Cc:	xiyuan@chromium.org, d...@chromium.org, nirnimesh@chromium.org, sato...@chromium.org, s...@chromium.org
Sev-2 Pri-1 Iteration-39 FixedIn-1099.0.0 FixedInIndex-25 OS-Chrome Type-Bug-Regression Cr-OS-Kernel-Power Cr-Stability Hotlist-TreeCloser M-15

Project Member Reported by mtennant@chromium.org, Sep 20, 2011

The suite_Smoke/desktopui_ScreenLocker test failed on the x86 generic full builder just now:

http://chromegw/i/chromiumos/builders/x86%20generic%20full/builds/410/steps/VMTest/logs/stdio

Interesting output:
Unhandled JSONInterfaceError: Automation call {'command': 'GetLoginInfo'} received empty response.  Perhaps the browser crashed.

And:

========== ERROR FILE /tmp/cbuildbotuv0rBC/test_harness/SimpleTestVerify/1_verify/suite_Smoke/desktopui_ScreenLocker/debug/desktopui_ScreenLocker.ERROR FOR TEST suite_Smoke/desktopui_ScreenLocker ==============
09/20 13:38:08 ERROR|  warnings:0029| /usr/local/lib/python2.6/site-packages/dbus/connection.py:242: DeprecationWarning: object.__init__() takes no parameters
09/20 13:38:08 ERROR|  warnings:0029|   super(Connection, self).__init__(*args, **kwargs)


=========== END ERROR FILE /tmp/cbuildbotuv0rBC/test_harness/SimpleTestVerify/1_verify/suite_Smoke/desktopui_ScreenLocker/debug/desktopui_ScreenLocker.ERROR FOR TEST suite_Smoke/desktopui_ScreenLocker ===========

Sep 20, 2011

#1 ol...@chromium.org

Looking at the debug output from a good and bad run.

Good run (from 'start ui' on):

09/20 13:41:53 DEBUG|base_utils:0074| Running 'start ui'
09/20 13:41:53 DEBUG|base_utils:0106| [stdout] ui start/running, process 3344
09/20 13:41:53 DEBUG|base_utils:0074| Running 'ls -tr1 /var/log/messages.*'
09/20 13:41:54 INFO |logging_ma:0560| 3397
09/20 13:41:54 INFO |logging_ma:0560| 3400
09/20 13:41:54 INFO |logging_ma:0560| 3402
09/20 13:41:54 DEBUG|base_utils:0074| Running 'mkdir -p /usr/local/autotest/packages'
09/20 13:41:54 DEBUG|base_utils:0074| Running 'ls /usr/local/autotest/packages'
09/20 13:41:54 DEBUG|base_utils:0074| Running 'ls /usr/local/autotest/packages/dep-pyauto_dep.tar.bz2'
09/20 13:41:54 DEBUG|base_utils:0074| Running 'md5sum /usr/local/autotest/packages/dep-pyauto_dep.tar.bz2 '
09/20 13:41:54 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:54 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:54 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:54 DEBUG|base_utils:0074| Running 'ls /usr/local/autotest/deps/pyauto_dep'
09/20 13:41:54 DEBUG|base_utils:0074| Running 'cat /usr/local/autotest/deps/pyauto_dep/.checksum'
09/20 13:41:54 DEBUG|base_utils:0074| Running 'md5sum /usr/local/autotest/packages/dep-pyauto_dep.tar.bz2 '
09/20 13:41:55 DEBUG|base_utils:0074| Running '/bin/sh /usr/local/autotest/deps/pyauto_dep/test_src/out/Release/setup_test_links.sh'
09/20 13:41:55 DEBUG|    pyauto:4052| Loading pyauto libs from /usr/local/autotest/deps/pyauto_dep/test_src/out/Release
09/20 13:41:56 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:56 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:56 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:57 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:57 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:57 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:57 DEBUG|auth_serve:0180| {'service': ['chromeoslogin'], 'skipvpage': ['true'], 'continue': ['chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html'], 'hl': ['en-US'], 'rm': ['hide'], 'sarp': ['1']}
09/20 13:41:57 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:41:57] "GET /accounts/ServiceLogin?service=chromeoslogin&skipvpage=true&sarp=1&rm=hide&continue=chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html&hl=en-US HTTP/1.1" 200 -

09/20 13:41:57 DEBUG|     httpd:0131| URL /accounts/ServiceLogin not in watch list
09/20 13:41:57 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/service_login.js
09/20 13:41:57 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:41:57] "GET /service_login.js HTTP/1.1" 200 -

09/20 13:41:57 DEBUG|     httpd:0131| URL /service_login.js not in watch list
09/20 13:41:57 DEBUG|auth_serve:0180| {'test_pwd': ['perfsmurf'], 'service': ['chromeoslogin'], 'test_email': ['performancetestaccount@gmail.com'], 'skipvpage': ['true'], 'continue': ['chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html'], 'hl': ['en-US'], 'rm': ['hide'], 'sarp': ['1']}
09/20 13:41:57 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:41:57] "GET /accounts/ServiceLogin?service=chromeoslogin&skipvpage=true&sarp=1&rm=hide&continue=chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html&hl=en-US&test_email=performancetestaccount%40gmail.com&test_pwd=perfsmurf HTTP/1.1" 200 -

09/20 13:41:57 DEBUG|     httpd:0131| URL /accounts/ServiceLogin not in watch list
09/20 13:41:57 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:57 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:57 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:41:57 DEBUG|auth_serve:0169| FieldStorage(None, None, [MiniFieldStorage('Email', 'performancetestaccount@gmail.com'), MiniFieldStorage('Passwd', 'perfsmurf'), MiniFieldStorage('continue', 'chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html?attemptToken=1316551317658')])
09/20 13:41:57 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:41:57] "POST /accounts/ProcessServiceLogin HTTP/1.1" 303 -

09/20 13:41:57 DEBUG|     httpd:0131| URL /accounts/ProcessServiceLogin not in watch list
09/20 13:41:57 DEBUG|auth_serve:0145| FieldStorage(None, None, [MiniFieldStorage('Email', 'performancetestaccount@gmail.com'), MiniFieldStorage('Passwd', 'perfsmurf'), MiniFieldStorage('PersistentCookie', 'true'), MiniFieldStorage('accountType', 'HOSTED_OR_GOOGLE'), MiniFieldStorage('source', 'chromeos'), MiniFieldStorage('service', 'cp')])
09/20 13:41:57 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:41:57] "POST /accounts/ClientLogin HTTP/1.1" 200 -

09/20 13:41:57 DEBUG|auth_serve:0145| FieldStorage(None, None, [MiniFieldStorage('Email', 'performancetestaccount@gmail.com'), MiniFieldStorage('Passwd', 'perfsmurf'), MiniFieldStorage('PersistentCookie', 'true'), MiniFieldStorage('accountType', 'GOOGLE'), MiniFieldStorage('source', 'chromeos'), MiniFieldStorage('service', 'cp')])
09/20 13:41:57 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:41:57] "POST /accounts/ClientLogin HTTP/1.1" 200 -

09/20 13:41:57 DEBUG|     httpd:0131| URL /accounts/ClientLogin not in watch list
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/support/chrome/bin/topic/1142433/inproduct
09/20 13:42:02 WARNI|     httpd:0234| (httpd error) localhost - - [20/Sep/2011 13:42:02] code 404, message File not found

09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "GET /support/chrome/bin/topic/1142433/inproduct?hl=en-US HTTP/1.1" 404 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /support/chrome/bin/topic/1142433/inproduct not in watch list
09/20 13:42:02 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/searchdomaincheck
09/20 13:42:02 WARNI|     httpd:0234| (httpd error) localhost - - [20/Sep/2011 13:42:02] code 404, message File not found

09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "GET /searchdomaincheck?format=domain&type=chrome HTTP/1.1" 404 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /searchdomaincheck not in watch list
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/searchdomaincheck
09/20 13:42:02 WARNI|     httpd:0234| (httpd error) localhost - - [20/Sep/2011 13:42:02] code 404, message File not found

09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "GET /searchdomaincheck?format=domain&type=chrome HTTP/1.1" 404 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /searchdomaincheck not in watch list
09/20 13:42:02 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'gaia')])
09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "POST /accounts/IssueAuthToken HTTP/1.1" 200 -

09/20 13:42:02 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'gaia'), MiniFieldStorage('Session', 'false')])
09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "POST /accounts/IssueAuthToken HTTP/1.1" 200 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:42:02 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'chromiumsync'), MiniFieldStorage('Session', 'true')])
09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "POST /accounts/IssueAuthToken HTTP/1.1" 403 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:42:02 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'talk'), MiniFieldStorage('Session', 'true')])
09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "POST /accounts/IssueAuthToken HTTP/1.1" 403 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:42:02 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'mobilesync'), MiniFieldStorage('Session', 'true')])
09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "POST /accounts/IssueAuthToken HTTP/1.1" 403 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:42:02 INFO |cros_ui_te:0310| Logged in as performancetestaccount@gmail.com.
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: dl.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: dl.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|miniFakeDn:0086| Response: dl.google.com. -> 127.0.0.1
09/20 13:42:02 DEBUG|auth_serve:0193| {'source': ['chromeos'], 'continue': ['http://www.google.com/webhp'], 'auth': ['aaaa']}
09/20 13:42:02 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:42:02] "GET /accounts/TokenAuth?continue=http://www.google.com/webhp&source=chromeos&auth=aaaa HTTP/1.1" 303 -

09/20 13:42:02 DEBUG|     httpd:0131| URL /accounts/TokenAuth not in watch list
09/20 13:42:02 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:42:02] "GET /webhp HTTP/1.1" 200 -

09/20 13:42:03 DEBUG|base_utils:0074| Running 'sync'
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: ibxdgwntcn. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: ibxdgwntcn. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: ibxdgwntcn. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: wbwezxxjgy. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: wbwezxxjgy. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: wbwezxxjgy. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: clnmilczyj. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: clnmilczyj. -> 127.0.0.1
09/20 13:42:03 DEBUG|miniFakeDn:0086| Response: clnmilczyj. -> 127.0.0.1
09/20 13:42:03 DEBUG|     httpd:0120| Translated path: /_
09/20 13:42:03 WARNI|     httpd:0046| (httpd error) localhost - - [20/Sep/2011 13:42:03] code 404, message File not found

09/20 13:42:03 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:42:03] "HEAD / HTTP/1.1" 404 -

09/20 13:42:03 DEBUG|     httpd:0120| Translated path: /_
09/20 13:42:03 WARNI|     httpd:0046| (httpd error) localhost - - [20/Sep/2011 13:42:03] code 404, message File not found

09/20 13:42:03 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:42:03] "HEAD / HTTP/1.1" 404 -

09/20 13:42:03 DEBUG|     httpd:0120| Translated path: /_
09/20 13:42:03 WARNI|     httpd:0046| (httpd error) localhost - - [20/Sep/2011 13:42:03] code 404, message File not found

09/20 13:42:03 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:42:03] "HEAD / HTTP/1.1" 404 -

09/20 13:42:04 DEBUG|base_utils:0074| Running 'echo 3 > /proc/sys/vm/drop_caches'
09/20 13:42:05 DEBUG|cros_ui_te:0343| Saving /home/chronos/user/log to /usr/local/autotest/results/default/desktopui_ScreenLocker/results/log-1316551325.871275.
09/20 13:42:05 DEBUG|base_utils:0074| Running 'logger "cros/login.py: Attempting StopSession..."'
09/20 13:42:05 DEBUG|base_utils:0074| Running 'ls -tr1 /var/log/messages.*'
09/20 13:42:08 DEBUG|base_utils:0074| Running 'logger "cros/login.py: StopSession complete."'
09/20 13:42:08 INFO |cros_ui_te:0425| Erasing fake owner state.
09/20 13:42:08 DEBUG|cros_ui_te:0136| Considering eth0
09/20 13:42:09 DEBUG|cros_ui_te:0140| Clearing local DNS for eth0
09/20 13:42:09 DEBUG|base_utils:0074| Running 'restart flimflam'
09/20 13:42:10 DEBUG|base_utils:0106| [stdout] flimflam start/running, process 3910
09/20 13:42:10 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:42:10 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:42:10 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:10 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 127.0.0.1
09/20 13:42:10 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:42:10 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:42:10 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:10 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 127.0.0.1
09/20 13:42:10 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:42:10 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:42:10 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:42:10 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 127.0.0.1
09/20 13:42:10 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:42:10 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:42:11 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 72.14.204.105
09/20 13:42:12 DEBUG|base_utils:0074| Running 'ls -tr1 /var/log/messages.*'
09/20 13:42:12 DEBUG|logging_ma:0561| Logging subprocess finished
09/20 13:42:12 DEBUG|logging_ma:0561| Logging subprocess finished

Sep 20, 2011

#2 ol...@chromium.org

bad run:

09/20 13:38:12 DEBUG|base_utils:0074| Running 'start ui'
09/20 13:38:12 DEBUG|base_utils:0106| [stdout] ui start/running, process 3394
09/20 13:38:12 DEBUG|base_utils:0074| Running 'ls -tr1 /var/log/messages.*'
09/20 13:38:13 INFO |logging_ma:0560| 3453
09/20 13:38:13 DEBUG|base_utils:0074| Running 'mkdir -p /usr/local/autotest/packages'
09/20 13:38:13 DEBUG|base_utils:0074| Running 'ls /usr/local/autotest/packages'
09/20 13:38:13 DEBUG|base_utils:0074| Running 'ls /usr/local/autotest/packages/dep-pyauto_dep.tar.bz2'
09/20 13:38:13 DEBUG|base_utils:0074| Running 'md5sum /usr/local/autotest/packages/dep-pyauto_dep.tar.bz2 '
09/20 13:38:13 DEBUG|base_utils:0074| Running 'ls /usr/local/autotest/deps/pyauto_dep'
09/20 13:38:13 DEBUG|base_utils:0074| Running 'cat /usr/local/autotest/deps/pyauto_dep/.checksum'
09/20 13:38:13 DEBUG|base_utils:0074| Running 'md5sum /usr/local/autotest/packages/dep-pyauto_dep.tar.bz2 '
09/20 13:38:14 DEBUG|base_utils:0074| Running '/bin/sh /usr/local/autotest/deps/pyauto_dep/test_src/out/Release/setup_test_links.sh'
09/20 13:38:14 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:14 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:14 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:16 DEBUG|    pyauto:4052| Loading pyauto libs from /usr/local/autotest/deps/pyauto_dep/test_src/out/Release
09/20 13:38:16 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:16 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:16 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:16 DEBUG|auth_serve:0180| {'service': ['chromeoslogin'], 'skipvpage': ['true'], 'continue': ['chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html'], 'hl': ['en-US'], 'rm': ['hide'], 'sarp': ['1']}
09/20 13:38:16 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:16] "GET /accounts/ServiceLogin?service=chromeoslogin&skipvpage=true&sarp=1&rm=hide&continue=chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html&hl=en-US HTTP/1.1" 200 -

09/20 13:38:16 DEBUG|     httpd:0131| URL /accounts/ServiceLogin not in watch list
09/20 13:38:16 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/service_login.js
09/20 13:38:16 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:16] "GET /service_login.js HTTP/1.1" 200 -

09/20 13:38:16 DEBUG|     httpd:0131| URL /service_login.js not in watch list
09/20 13:38:17 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:17 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:17 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:18 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:18 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:18 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:18 DEBUG|auth_serve:0180| {'service': ['chromeoslogin'], 'skipvpage': ['true'], 'continue': ['chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html'], 'hl': ['en-US'], 'rm': ['hide'], 'sarp': ['1']}
09/20 13:38:18 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:18] "GET /accounts/ServiceLogin?service=chromeoslogin&skipvpage=true&sarp=1&rm=hide&continue=chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html&hl=en-US HTTP/1.1" 200 -

09/20 13:38:18 DEBUG|     httpd:0131| URL /accounts/ServiceLogin not in watch list
09/20 13:38:18 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/service_login.js
09/20 13:38:18 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:18] "GET /service_login.js HTTP/1.1" 200 -

09/20 13:38:18 DEBUG|     httpd:0131| URL /service_login.js not in watch list
09/20 13:38:18 DEBUG|auth_serve:0180| {'test_pwd': ['perfsmurf'], 'service': ['chromeoslogin'], 'test_email': ['performancetestaccount@gmail.com'], 'skipvpage': ['true'], 'continue': ['chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html'], 'hl': ['en-US'], 'rm': ['hide'], 'sarp': ['1']}
09/20 13:38:18 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:18] "GET /accounts/ServiceLogin?service=chromeoslogin&skipvpage=true&sarp=1&rm=hide&continue=chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html&hl=en-US&test_email=performancetestaccount%40gmail.com&test_pwd=perfsmurf HTTP/1.1" 200 -

09/20 13:38:18 DEBUG|     httpd:0131| URL /accounts/ServiceLogin not in watch list
09/20 13:38:18 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:18 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:18 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:18 DEBUG|auth_serve:0169| FieldStorage(None, None, [MiniFieldStorage('Email', 'performancetestaccount@gmail.com'), MiniFieldStorage('Passwd', 'perfsmurf'), MiniFieldStorage('continue', 'chrome-extension://mfffpogegjflfpflabcdkioaeobkgjik/success.html?attemptToken=1316551098938')])
09/20 13:38:18 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:18] "POST /accounts/ProcessServiceLogin HTTP/1.1" 303 -

09/20 13:38:18 DEBUG|     httpd:0131| URL /accounts/ProcessServiceLogin not in watch list
09/20 13:38:19 DEBUG|auth_serve:0145| FieldStorage(None, None, [MiniFieldStorage('Email', 'performancetestaccount@gmail.com'), MiniFieldStorage('Passwd', 'perfsmurf'), MiniFieldStorage('PersistentCookie', 'true'), MiniFieldStorage('accountType', 'HOSTED_OR_GOOGLE'), MiniFieldStorage('source', 'chromeos'), MiniFieldStorage('service', 'cp')])
09/20 13:38:19 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:19] "POST /accounts/ClientLogin HTTP/1.1" 200 -

09/20 13:38:19 DEBUG|auth_serve:0145| FieldStorage(None, None, [MiniFieldStorage('Email', 'performancetestaccount@gmail.com'), MiniFieldStorage('Passwd', 'perfsmurf'), MiniFieldStorage('PersistentCookie', 'true'), MiniFieldStorage('accountType', 'GOOGLE'), MiniFieldStorage('source', 'chromeos'), MiniFieldStorage('service', 'cp')])
09/20 13:38:19 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:19] "POST /accounts/ClientLogin HTTP/1.1" 200 -

09/20 13:38:19 DEBUG|     httpd:0131| URL /accounts/ClientLogin not in watch list
09/20 13:38:22 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:22 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:22 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:22 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/support/chrome/bin/topic/1142433/inproduct
09/20 13:38:22 WARNI|     httpd:0234| (httpd error) localhost - - [20/Sep/2011 13:38:22] code 404, message File not found

09/20 13:38:22 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:22] "GET /support/chrome/bin/topic/1142433/inproduct?hl=en-US HTTP/1.1" 404 -

09/20 13:38:22 DEBUG|     httpd:0131| URL /support/chrome/bin/topic/1142433/inproduct not in watch list
09/20 13:38:22 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:22 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:22 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:22 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/searchdomaincheck
09/20 13:38:22 WARNI|     httpd:0234| (httpd error) localhost - - [20/Sep/2011 13:38:22] code 404, message File not found

09/20 13:38:22 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:22] "GET /searchdomaincheck?format=domain&type=chrome HTTP/1.1" 404 -

09/20 13:38:22 DEBUG|     httpd:0131| URL /searchdomaincheck not in watch list
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: dl.google.com. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: dl.google.com. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: dl.google.com. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:24 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/searchdomaincheck
09/20 13:38:24 WARNI|     httpd:0234| (httpd error) localhost - - [20/Sep/2011 13:38:24] code 404, message File not found

09/20 13:38:24 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:24] "GET /searchdomaincheck?format=domain&type=chrome HTTP/1.1" 404 -

09/20 13:38:24 DEBUG|     httpd:0131| URL /searchdomaincheck not in watch list
09/20 13:38:24 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'gaia')])
09/20 13:38:24 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:24] "POST /accounts/IssueAuthToken HTTP/1.1" 200 -

09/20 13:38:24 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'gaia'), MiniFieldStorage('Session', 'false')])
09/20 13:38:24 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:24] "POST /accounts/IssueAuthToken HTTP/1.1" 200 -

09/20 13:38:24 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:38:24 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'chromiumsync'), MiniFieldStorage('Session', 'true')])
09/20 13:38:24 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:24] "POST /accounts/IssueAuthToken HTTP/1.1" 403 -

09/20 13:38:24 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:38:24 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'talk'), MiniFieldStorage('Session', 'true')])
09/20 13:38:24 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:24] "POST /accounts/IssueAuthToken HTTP/1.1" 403 -

09/20 13:38:24 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:38:24 DEBUG|auth_serve:0153| FieldStorage(None, None, [MiniFieldStorage('SID', '1234'), MiniFieldStorage('LSID', '5678'), MiniFieldStorage('service', 'mobilesync'), MiniFieldStorage('Session', 'true')])
09/20 13:38:24 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:24] "POST /accounts/IssueAuthToken HTTP/1.1" 403 -

09/20 13:38:24 DEBUG|     httpd:0131| URL /accounts/IssueAuthToken not in watch list
09/20 13:38:24 INFO |cros_ui_te:0310| Logged in as performancetestaccount@gmail.com.
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: mvjsvtvizb. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: mvjsvtvizb. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: mvjsvtvizb. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: hylppylwob. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: hylppylwob. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: hylppylwob. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: vcjstyizte. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: vcjstyizte. -> 127.0.0.1
09/20 13:38:24 DEBUG|miniFakeDn:0086| Response: vcjstyizte. -> 127.0.0.1
09/20 13:38:24 DEBUG|     httpd:0120| Translated path: /_
09/20 13:38:24 WARNI|     httpd:0046| (httpd error) localhost - - [20/Sep/2011 13:38:24] code 404, message File not found

09/20 13:38:24 DEBUG|     httpd:0120| Translated path: /_
09/20 13:38:24 WARNI|     httpd:0046| (httpd error) localhost - - [20/Sep/2011 13:38:24] code 404, message File not found

09/20 13:38:24 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:38:24] "HEAD / HTTP/1.1" 404 -

09/20 13:38:24 DEBUG|     httpd:0120| Translated path: /_
09/20 13:38:24 WARNI|     httpd:0046| (httpd error) localhost - - [20/Sep/2011 13:38:24] code 404, message File not found

09/20 13:38:24 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:38:24] "HEAD / HTTP/1.1" 404 -

09/20 13:38:24 DEBUG|auth_serve:0193| {'source': ['chromeos'], 'continue': ['http://www.google.com/webhp'], 'auth': ['aaaa']}
09/20 13:38:24 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:38:24] "HEAD / HTTP/1.1" 404 -

09/20 13:38:24 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:24] "GET /accounts/TokenAuth?continue=http://www.google.com/webhp&source=chromeos&auth=aaaa HTTP/1.1" 303 -

09/20 13:38:24 DEBUG|     httpd:0131| URL /accounts/TokenAuth not in watch list
09/20 13:38:24 DEBUG|     httpd:0051| localhost - - [20/Sep/2011 13:38:24] "GET /webhp HTTP/1.1" 200 -

09/20 13:38:25 DEBUG|base_utils:0074| Running 'sync'
09/20 13:38:25 DEBUG|base_utils:0074| Running 'echo 3 > /proc/sys/vm/drop_caches'
09/20 13:38:29 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:29 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:29 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:38:29 DEBUG|     httpd:0120| Translated path: /usr/local/autotest/cros/support/chrome/bin/topic/1142433/inproduct
09/20 13:38:29 WARNI|     httpd:0234| (httpd error) localhost - - [20/Sep/2011 13:38:29] code 404, message File not found

09/20 13:38:29 DEBUG|     httpd:0239| localhost - - [20/Sep/2011 13:38:29] "GET /support/chrome/bin/topic/1142433/inproduct?hl=en-US HTTP/1.1" 404 -

09/20 13:38:29 DEBUG|     httpd:0131| URL /support/chrome/bin/topic/1142433/inproduct not in watch list
09/20 13:39:21 INFO |logging_ma:0560| chrome/test/pyautolib/pyautolib.cc:338: Failure
09/20 13:39:21 INFO |logging_ma:0560| Value of: automation()->SendJSONRequest(request, timeout, &response)
09/20 13:39:21 INFO |logging_ma:0560|   Actual: false
09/20 13:39:21 INFO |logging_ma:0560| Expected: true
09/20 13:39:21 DEBUG|cros_ui_te:0343| Saving /home/chronos/user/log to /usr/local/autotest/results/default/desktopui_ScreenLocker/results/log-1316551161.803032.
09/20 13:39:21 DEBUG|base_utils:0074| Running 'logger "cros/login.py: Attempting StopSession..."'
09/20 13:39:22 DEBUG|base_utils:0074| Running 'ls -tr1 /var/log/messages.*'
09/20 13:39:30 DEBUG|base_utils:0074| Running 'logger "cros/login.py: StopSession complete."'
09/20 13:39:30 INFO |cros_ui_te:0425| Erasing fake owner state.
09/20 13:39:31 DEBUG|cros_ui_te:0136| Considering eth0
09/20 13:39:31 DEBUG|cros_ui_te:0140| Clearing local DNS for eth0
09/20 13:39:31 DEBUG|base_utils:0074| Running 'restart flimflam'
09/20 13:39:31 DEBUG|base_utils:0106| [stdout] flimflam start/running, process 3959
09/20 13:39:31 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:39:31 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:39:31 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:39:31 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 127.0.0.1
09/20 13:39:32 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:39:32 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:39:32 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:39:32 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 127.0.0.1
09/20 13:39:32 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:39:32 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:39:32 DEBUG|miniFakeDn:0086| Response: www.google.com. -> 127.0.0.1
09/20 13:39:32 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 127.0.0.1
09/20 13:39:32 DEBUG|cros_ui_te:0088| Attempting to resolve www.google.com. to 127.0.0.1
09/20 13:39:32 DEBUG|base_utils:0074| Running 'ping -c 1 -w 1 -q www.google.com.'
09/20 13:39:33 DEBUG|cros_ui_te:0091| Resolve attempt for www.google.com. got 72.14.204.147
09/20 13:39:34 DEBUG|base_utils:0074| Running 'ls -tr1 /var/log/messages.*'
09/20 13:39:34 DEBUG|logging_ma:0561| Logging subprocess finished
09/20 13:39:34 DEBUG|logging_ma:0561| Logging subprocess finished

Sep 20, 2011

#3 ol...@chromium.org

While the logs are asyncronous so things are ordered differently, it seems like one of the significant differences is that the bad log shows the re-request of the last URL (/support/chrome/.../inproduct). It seems to be data related to new tab contents. Not sure if it's significant or not that it was re-requested, but the other log does not seem to show it.

Sep 20, 2011

#4 cmasone@chromium.org

If there's no stack trace, then this should be duped against 20297

Sep 22, 2011

#5 achuith@chromium.org

Happened just now:
http://chromeos-botmaster.mtv.corp.google.com:8026/builders/stumpy-binary/builds/788

ERROR	desktopui_ScreenLocker	desktopui_ScreenLocker	timestamp=1316735650	localtime=Sep 22 16:54:10	Unhandled JSONInterfaceError: Automation call {'username': 'performancetestaccount@gmail.com', 'password': 'perfsmurf', 'command': 'Login'} received empty response.  Perhaps the browser crashed.

Labels: -Sev-3 Sev-2

Sep 22, 2011

#6 achuith@chromium.org

xiyuan: Any chance this bug is a dupe of crosbug.com/20323?

Cc: xiyuan@chromium.org

Sep 22, 2011

#7 xiyuan@chromium.org

Nope, the log shows it passed login point so it's different from 20323.

Sep 23, 2011

#8 davemo...@chromium.org

This happened again today:
http://build.chromium.org/p/chromiumos/builders/x86%20generic%20PFQ/builds/993/steps/VMTest/logs/stdio

Oshima, is this yours?

Owner: oshima@chromium.org

Sep 23, 2011

#9 nirnimesh@chromium.org

Dtu was able to repro the problem manually the last time. In some cases screen unlocking really takes a long time; automation times out.

Cc: d...@chromium.org

Sep 23, 2011

#10 osh...@google.com

could be same as 19840? Dtu, can you tell?

Sep 23, 2011

#11 davemo...@chromium.org

Disabling test until bug is fixed.

Status: Assigned
Owner: nkostylev@chromium.org
Labels: Pri-1

Sep 23, 2011

#12 nirnimesh@chromium.org

I take back comment #9. It might not be the same problem as issue 19840 -- this one is clearly failing when calling GetLoginInfo().

Comment #5 is unrelated to this test.

Sep 23, 2011

#13 bugdroid1@chromium.org

Commit: 746e84eae4cb598b08f34c42cbc7d936f2cf2ef6
 Email: thutt@chromium.org

chromium-os:20641: Disable desktopui_ScreenLocker

BUG=chromium-os:20641
TEST=Let the buildbots run with it after committed.
Change-Id: If04a3a9fcda8e7735595149d3b8f856d894e5332
Signed-off-by: Taylor Hutt <thutt@chromium.org>
Reviewed-on: http://gerrit.chromium.org/gerrit/8244
Reviewed-by: Dave Moore <davemoore@chromium.org>

M	client/site_tests/suite_Smoke/control

Sep 26, 2011

#14 nkostylev@chromium.org

(No comment was entered for this change.)

Labels: Iteration-39

Sep 26, 2011

#15 nkostylev@chromium.org

I've enabled this test locally and it consistently fails with
17:49:29 INFO | 		FAIL	desktopui_ScreenLocker	desktopui_ScreenLocker	timestamp=1317044968	localtime=Sep 26 17:49:28	Unhandled JSONInterfaceError: Automation call {'command': 'LockScreen'} received empty response.  Perhaps the browser crashed.

Investigating.

Status: Started
Cc: nirnimesh@chromium.org

Sep 26, 2011

#16 xiyuan@chromium.org

I wonder whether you are seeing http://crosbug.com/20323 locally? If you do see "WaitForInitialLoads" in the logs, please pass them to me and I would like to check it out.

Sep 26, 2011

#17 nkostylev@chromium.org

Here's what in /var/log/power_manager/powerd.LATEST
[0926/101246:ERROR:dbus.cc(205)] Failed to become the primary owner. Releasing . . .
[0926/101246:WARNING:dbus.cc(215)] Service name already owned by this process
[0926/101246:INFO:dbus.cc(226)] ReleaseName returned code 1
[0926/101246:ERROR:powerd_main.cc(128)] Cannot register power manager service

dbus-send --system --type=method_call --print-reply --dest=org.chromium.PowerManager /org/chromium/PowerManager org.chromium.PowerManager.RequestLockScreen

is not working.

http://gerrit.chromium.org/gerrit/#change,8166 seems to be related.

Status: Assigned
Owner: s...@chromium.org
Cc: nkostylev@chromium.org sato...@chromium.org
Labels: Mstone-R15 Regression

Sep 26, 2011

#18 nkostylev@chromium.org

Re: 16
No "WaitForInitialLoads" in test logs, automation logs in fine, browser is not crashing.

If I manually lock the screen (putting device to sleep) then test starts waiting for unlock operation.

Sep 26, 2011

#19 sato...@chromium.org

Re comment #17, the dbus-send not working is indeed caused by http://gerrit.chromium.org/gerrit/#change,8166. I reverted the patch locally and dbus-send now works. This is mostly my fault I suggested him to register the service name, but I wasn't aware that the dbus service name was registered from power_main.cc as follows:

powerd_main.cc

  else if (!manager.Register(chromeos::dbus::GetSystemBusConnection()))
    LOG(ERROR) << "Cannot register power manager service";

power-manager_service.cc:

bool PowerManagerService::Register(
    const chromeos::dbus::BusConnection &connection) {
  if (!chromeos::dbus::AbstractDbusService::Register(connection))
    return false;

abstract_dbus_service.cc:

bool AbstractDbusService::Register(const chromeos::dbus::BusConnection &conn) {
  return RegisterExclusiveService(conn,
                                  service_interface(),
                                  service_name(),
                                  service_path(),
                                  service_object());

dbus.cc:


bool RegisterExclusiveService(const BusConnection& connection,
                              const char* interface_name,
                              const char* service_name,
                              const char* service_path,
                              GObject* object) {
  ...
  if (!org_freedesktop_DBus_request_name(proxy.gproxy(),
                                         service_name,
                                         0,
                                         &result,
                                         &Resetter(&err).lvalue())) {

Sep 26, 2011

#20 nkostylev@chromium.org

I've checked that test runs ok with this revert.

Sep 26, 2011

#21 nkostylev@chromium.org

So revert fixes
Unhandled JSONInterfaceError: Automation call {'command': 'LockScreen'} received empty response.

Original
Unhandled JSONInterfaceError: Automation call {'command': 'GetLoginInfo'} received empty response.
still might be an issue. I'll take this issue back and will look tomorrow.

Owner: nkostylev@chromium.org
Cc: -nkostylev@chromium.org s...@chromium.org

Sep 26, 2011

#22 bugdroid1@chromium.org

Commit: cfa9f780c9c7f6b1a9e446925c187fc671ff7a63
 Email: satorux@chromium.org

Revert "powerd: register power manager with dbus connection"

This reverts commit 0adbbed0b605f5f169386a950e0fc26ebf22f741.

Reason: It turned out that the servic name was registered at
the end of main() in powerd_main.cc by

  else if (!manager.Register(chromeos::dbus::GetSystemBusConnection()))

Hence we should not register the name again in powerd.cc.

TEST=confirm that the screen lock works by: dbus-send --system --type=method_call --print-reply --dest=org.chromium.PowerManager /org/chromium/PowerManager org.chromium.PowerManager.RequestLockScreen
BUG=chromium-os:20641,chromium-os:20079

Change-Id: Id2c3ad737ac8d97afd5d03d9deaab2065e4cb3b5
Reviewed-on: http://gerrit.chromium.org/gerrit/8277
Reviewed-by: Simon Que <sque@chromium.org>
Tested-by: Satoru Takabayashi <satorux@chromium.org>

M	powerd.cc

Sep 27, 2011

#23 nkostylev@chromium.org

Update:

After change that broke PowerManager dbus was reverted I'm unable to reproduce this issue locally. I want to enabling desktopui_ScreenLocker test again to get more information if it fails on the buildbots.

http://gerrit.chromium.org/gerrit/#change,8336

However commit queue has failed twice with desktopui_UrlFetch.not-live.

Status: Started

Sep 28, 2011

#24 bugdroid1@chromium.org

Commit: be135af970afee13fae8ab5375388d1bab5d8b05
 Email: nkostylev@chromium.org

Revert "chromium-os:20641: Disable desktopui_ScreenLocker" This reverts commit 746e84eae4cb598b08f34c42cbc7d936f2cf2ef6.

After change that broke PowerManager dbus was reverted I'm unable to reproduce this issue locally.
Enabling desktopui_ScreenLocker test again to get more information if it fails on the buildbots.

BUG=chromium-os:20641
TEST=desktopui_ScreenLocker, known failures http://crosbug.com/20429, http://crosbug.com/20305

Change-Id: If04a3a9fcda8e6328762122d3b8f521d894e0922
Reviewed-on: http://gerrit.chromium.org/gerrit/8336
Commit-Ready: Nikita Kostylev <nkostylev@chromium.org>
Reviewed-by: Nikita Kostylev <nkostylev@chromium.org>
Tested-by: Nikita Kostylev <nkostylev@chromium.org>

M	client/site_tests/suite_Smoke/control

Sep 28, 2011

#25 nkostylev@chromium.org

Test was enabled back.

Status: Fixed

Sep 29, 2011

#26 bhaves...@chromium.org

(No comment was entered for this change.)

Labels: -Area-DesktopUI Area-Power

Oct 6, 2011

#27 kr...@chromium.org

(No comment was entered for this change.)

Status: Verified

Nov 8, 2011

#28 chromeos...@chromium.org

(No comment was entered for this change.)

Labels: FixedIn-1098.0.0

Jan 9, 2012

#29 chromeos...@chromium.org

(No comment was entered for this change.)

Labels: -FixedIn-1098.0.0 FixedIn-1099.0.0

Jan 20, 2012

#30 chromeos...@chromium.org

(No comment was entered for this change.)

Labels: FixedInIndex-25

Mar 6, 2013

#31 bugdroid1@chromium.org

(No comment was entered for this change.)

Labels: OS-Chrome

Mar 9, 2013

#32 bugdroid1@chromium.org

(No comment was entered for this change.)

Labels: -Regression Type-Bug-Regression

Mar 9, 2013

#33 bugdroid1@chromium.org

(No comment was entered for this change.)

Labels: -Crash -Area-Power -TreeCloser Cr-OS-Kernel-Power Cr-Stability Hotlist-TreeCloser

Mar 10, 2013

#34 bugdroid1@chromium.org

(No comment was entered for this change.)

Labels: -Mstone-R15 M-15

► Sign in to add a comment