920728 - gdb won't start with mochitest-browser

Status: RESOLVED FIXED

(Firefox Build System :: General, defect)

Description

[Ivan Alagenchev :ialagenchev]

I'm trying to start the debugger while running a mochitest and I'm getting this error:

$ ~/mozilla-central/mach mochitest-browser --debugger=gdb browser_webconsole_block_mixedcontent_securityerrors.js 
 0:00.60 MochitestServer : launching [u'/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/dist/bin/xpcshell', '-g', u'/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/dist/bin', '-v', '170', '-f', '/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpYRvKXi'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', './server.js']
 0:00.60 runtests.py | Server pid: 9771
WARNING: failed to bind socket: file /home/alagenchev/mozilla-central/netwerk/base/src/nsServerSocket.cpp, line 354
System JS : ERROR (null):0
                     uncaught exception: 2147746065
WARNING: NS_ENSURE_TRUE(compMgr) failed: file /home/alagenchev/mozilla-central/xpcom/glue/nsComponentManagerUtils.cpp, line 58
WARNING: OOPDeinit() without successful OOPInit(): file /home/alagenchev/mozilla-central/toolkit/crashreporter/nsExceptionHandler.cpp, line 2305
nsStringStats
 => mAllocCount:           2669
 => mReallocCount:          373
 => mFreeCount:            2669
 => mShareCount:           8220
 => mAdoptCount:             67
 => mAdoptFreeCount:         67
3:00.81 TEST-UNEXPECTED-FAIL | runtests.py | Timed out while waiting for server startup.

Comment 1

[Ivan Alagenchev :ialagenchev]

I reported the above from a Linux machine. I also tested on a different computer - Mac and got the following:
Traceback (most recent call last):
  File "/Users/ialagenchev/mozilla-central/obj-x86_64-apple-darwin12.4.0/_tests/testing/mochitest/runtests.py", line 1041, in runTests
    onLaunch=onLaunch
  File "/Users/ialagenchev/mozilla-central/obj-x86_64-apple-darwin12.4.0/_tests/testing/mochitest/runtests.py", line 930, in runApp
    outputHandler.finish(proc.didTimeout)
AttributeError: 'Popen' object has no attribute 'didTimeout'
 5:19.48 Automation Error: Received unexpected exception while running application

 5:19.52
 5:19.52 WARNING | leakcheck | missing output line for total leaks!
 5:19.52 runtests.py | Running tests: end.

Comment 2

[Ivan Alagenchev :ialagenchev]

Here is a more detailed message:
 0:01.50 MochitestServer : launching [u'/home/alagenchev/sdb/mozilla-central/obj-x86_64-unknown-linux-gnu/dist/bin/xpcshell', '-g', u'/home/alagenchev/sdb/mozilla-central/obj-x86_64-unknown-linux-gnu/dist/bin', '-v', '170', '-f', '/home/alagenchev/sdb/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpsOe19P'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', './server.js']
 0:01.50 runtests.py | Server pid: 4017
 0:10.53 runtests.py | Websocket server pid: 4028
 0:10.69 runtests.py | Running tests: start.

pk12util: PKCS12 IMPORT SUCCESSFUL
 0:12.25 INFO | runtests.py | SSL tunnel pid: 4048
 0:12.30 INFO | runtests.py | Application pid: 4050
Traceback (most recent call last):
  File "/home/alagenchev/sdb/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/runtests.py", line 1041, in runTests
    onLaunch=onLaunch
  File "/home/alagenchev/sdb/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/runtests.py", line 912, in runApp
    outputHandler.setProcess(proc, timeout)
  File "/home/alagenchev/sdb/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/runtests.py", line 1151, in setProcess
    stdin=proc.proc.stdout,
AttributeError: 'Popen' object has no attribute 'proc'
 0:12.33 Automation Error: Received unexpected exception while running application

 0:12.42 WARNING | leakcheck | refcount logging is off, so leaks can't be detected!
 0:12.42 runtests.py | Running tests: end.

Comment 3

[(not currently active) Ted Mielczarek]

Possible regression from bug 746243?

Comment 4

[Jeff Hammel]

(In reply to Ted Mielczarek [:ted.mielczarek] from comment #3)
> Possible regression from bug 746243?

Assuredly

Comment 5

[Jeff Hammel]

Attachment: i believe this fixes

I can connect to gdb normally, so it seems:

│./mach mochitest-browser --debugger=gdb  browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js
 0:00.40 MochitestServer : launching [u'/home/jhammel/mozilla/src/obj-browser/dist/bin/xpcshell', '-g', u'/home/jhammel/mozilla/src/obj-browser/dist/bin', '-v', '170', '-f', '/home/jhammel/mozilla/src/obj-browser/_tests/testing/mochitest/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpkC9E7G'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', './server.js']
 0:00.40 runtests.py | Server pid: 11072
 0:01.41 runtests.py | Websocket server pid: 11087
 0:01.41 runtests.py | Running tests: start.

pk12util: PKCS12 IMPORT SUCCESSFUL
 0:01.52 INFO | runtests.py | SSL tunnel pid: 11107
 0:01.53 INFO | runtests.py | Application pid: 11109
failed to bind socket
Shutting down...
Reading symbols from /home/jhammel/mozilla/src/obj-browser/dist/bin/firefox...done.
(gdb) run
Starting program: /home/jhammel/mozilla/src/obj-browser/dist/bin/firefox about:blank -foreground -profile /tmp/tmpkC9E7G
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
warning: File "/home/jhammel/mozilla/src/obj-browser/toolkit/library/libxul.so-gdb.py" auto-loading has been declined by your `auto-load safe-path' set to "$debugdir:$datadir/auto-load".
To enable execution of this file add
	add-auto-load-safe-path /home/jhammel/mozilla/src/obj-browser/toolkit/library/libxul.so-gdb.py
line to your configuration file "/home/jhammel/.gdbinit".
To completely disable this security protection add
	set auto-load safe-path /
line to your configuration file "/home/jhammel/.gdbinit".
For more information about this security protection see the
"Auto-loading safe path" section in the GDB manual.  E.g., run from the shell:
	info "(gdb)Auto-loading safe path"
[New Thread 0x7fffe970f700 (LWP 11184)]
[Thread 0x7fffe970f700 (LWP 11184) exited]

(process:11137): GLib-CRITICAL **: g_slice_set_config: assertion `sys_page_size == 0' failed
[New Thread 0x7fffe970f700 (LWP 11186)]
[New Thread 0x7fffe33ff700 (LWP 11187)]
[New Thread 0x7fffe2bfe700 (LWP 11188)]
[New Thread 0x7fffe23fd700 (LWP 11189)]
[New Thread 0x7fffe1bfc700 (LWP 11190)]
[New Thread 0x7fffe0bbc700 (LWP 11191)]
[New Thread 0x7fffe03bb700 (LWP 11192)]
[New Thread 0x7fffde6ff700 (LWP 11193)]
[New Thread 0x7fffdf4f8700 (LWP 11194)]
[New Thread 0x7fffdd7ff700 (LWP 11195)]
[New Thread 0x7fffdc18f700 (LWP 11196)]
[New Thread 0x7fffdb98e700 (LWP 11197)]
[New Thread 0x7fffdb18d700 (LWP 11198)]
[New Thread 0x7fffda98c700 (LWP 11199)]
[New Thread 0x7fffda18b700 (LWP 11200)]
[New Thread 0x7fffd998a700 (LWP 11201)]
[New Thread 0x7fffd9189700 (LWP 11202)]
[New Thread 0x7fffd8988700 (LWP 11203)]
[New Thread 0x7fffd8187700 (LWP 11204)]
[New Thread 0x7fffd7986700 (LWP 11205)]
[New Thread 0x7fffd72ff700 (LWP 11206)]
[New Thread 0x7fffd6bff700 (LWP 11207)]
[New Thread 0x7fffcc497700 (LWP 11208)]
[New Thread 0x7fffcbaff700 (LWP 11209)]
[New Thread 0x7fffcb2ff700 (LWP 11210)]
[New Thread 0x7fffca7ff700 (LWP 11211)]
[New Thread 0x7fffc9cff700 (LWP 11212)]
[New Thread 0x7fffc93ff700 (LWP 11213)]
[New Thread 0x7fffc87fb700 (LWP 11214)]
[New Thread 0x7fffc7dff700 (LWP 11215)]
[New Thread 0x7fffc73ff700 (LWP 11216)]
[New Thread 0x7fffc6bfe700 (LWP 11217)]
[Thread 0x7fffcc497700 (LWP 11208) exited]
[Thread 0x7fffc87fb700 (LWP 11214) exited]
[New Thread 0x7fffc5fff700 (LWP 11218)]
[Thread 0x7fffc7dff700 (LWP 11215) exited]
[Thread 0x7fffc73ff700 (LWP 11216) exited]
[New Thread 0x7fffcc497700 (LWP 11219)]
[Thread 0x7fffc6bfe700 (LWP 11217) exited]
[New Thread 0x7fffc87fb700 (LWP 11220)]
[New Thread 0x7fffc6bfe700 (LWP 11221)]
*** Start BrowserChrome Test Results ***
TEST-INFO | checking window state
[New Thread 0x7fffdde56700 (LWP 11222)]
TEST-START | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js
[New Thread 0x7fffc7dff700 (LWP 11223)]
TEST-INFO | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Console message: PAC file installed from data: URI
[New Thread 0x7fffc73ff700 (LWP 11224)]
[New Thread 0x7fffc2fff700 (LWP 11225)]
[New Thread 0x7fffc27fe700 (LWP 11226)]
TEST-INFO | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Console message: [JavaScript Error: "Blocked loading mixed display content "http://example.com/tests/image/test/mochitest/blue.png"" {file: "https://example.com/browser/browser/devtools/webconsole/test/test-mixedcontent-securityerrors.html" line: 0}]
TEST-INFO | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Console message: [JavaScript Error: "Blocked loading mixed display content "http://example.com/tests/image/test/mochitest/blue.png"" {file: "https://example.com/browser/browser/devtools/webconsole/test/test-mixedcontent-securityerrors.html" line: 0}]
TEST-INFO | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Console message: [JavaScript Error: "Blocked loading mixed active content "http://example.com/"" {file: "https://example.com/browser/browser/devtools/webconsole/test/test-mixedcontent-securityerrors.html" line: 0}]
[New Thread 0x7fffbb1ff700 (LWP 11228)]
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | matched rule: Logged blocking mixed active content
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | matched rule: Logged blocking mixed passive content - image
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | link element
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | link class name
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Clicking the Learn More Warning node opens the desired page
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Mixed Content Doorhanger didn't appear
TEST-INFO | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Console message: [JavaScript Warning: "Loading mixed (insecure) display content on a secure page "http://example.com/tests/image/test/mochitest/blue.png"" {file: "https://example.com/browser/browser/devtools/webconsole/test/test-mixedcontent-securityerrors.html" line: 0}]
TEST-INFO | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Console message: [JavaScript Warning: "Loading mixed (insecure) display content on a secure page "http://example.com/tests/image/test/mochitest/blue.png"" {file: "https://example.com/browser/browser/devtools/webconsole/test/test-mixedcontent-securityerrors.html" line: 0}]
TEST-INFO | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Console message: [JavaScript Warning: "Loading mixed (insecure) active content on a secure page "http://example.com/"" {file: "https://example.com/browser/browser/devtools/webconsole/test/test-mixedcontent-securityerrors.html" line: 0}]
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | matched rule: Logged blocking mixed passive content - image
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | matched rule: Logged blocking mixed active content
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | link element
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | link class name
TEST-PASS | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | Clicking the Learn More Warning node opens the desired page
INFO TEST-END | chrome://mochitests/content/browser/browser/devtools/webconsole/test/browser_webconsole_block_mixedcontent_securityerrors.js | finished in 1033ms
TEST-INFO | checking window state

INFO TEST-START | Shutdown
Browser Chrome Test Summary
	Passed: 11
	Failed: 0
	Todo: 0

*** End BrowserChrome Test Results ***
[Thread 0x7fffdc18f700 (LWP 11196) exited]
[Thread 0x7fffc2fff700 (LWP 11225) exited]
[Thread 0x7fffe33ff700 (LWP 11187) exited]
[Thread 0x7fffc27fe700 (LWP 11226) exited]
[New Thread 0x7fffc27fe700 (LWP 11233)]
[New Thread 0x7fffe33ff700 (LWP 11234)]
[Thread 0x7fffe33ff700 (LWP 11234) exited]
[Thread 0x7fffdde56700 (LWP 11222) exited]
[New Thread 0x7fffe33ff700 (LWP 11235)]
[Thread 0x7fffca7ff700 (LWP 11211) exited]
[Thread 0x7fffc7dff700 (LWP 11223) exited]
[Thread 0x7fffe33ff700 (LWP 11235) exited]
[Thread 0x7fffdf4f8700 (LWP 11194) exited]
[Thread 0x7fffc5fff700 (LWP 11218) exited]
[Thread 0x7fffcbaff700 (LWP 11209) exited]
[Thread 0x7fffc9cff700 (LWP 11212) exited]
[Thread 0x7fffd72ff700 (LWP 11206) exited]
[Thread 0x7fffc87fb700 (LWP 11220) exited]
[Thread 0x7fffc93ff700 (LWP 11213) exited]
[Thread 0x7fffcc497700 (LWP 11219) exited]
[Thread 0x7fffde6ff700 (LWP 11193) exited]
[Thread 0x7fffdd7ff700 (LWP 11195) exited]
[Thread 0x7fffc6bfe700 (LWP 11221) exited]
[Thread 0x7fffc73ff700 (LWP 11224) exited]
[Thread 0x7fffc27fe700 (LWP 11233) exited]
[Thread 0x7fffbb1ff700 (LWP 11228) exited]
[Thread 0x7fffd7986700 (LWP 11205) exited]
[Thread 0x7fffcb2ff700 (LWP 11210) exited]
[Thread 0x7fffd6bff700 (LWP 11207) exited]
[Thread 0x7fffe970f700 (LWP 11186) exited]
[Thread 0x7fffe1bfc700 (LWP 11190) exited]
[Thread 0x7fffdb98e700 (LWP 11197) exited]
[Thread 0x7fffe03bb700 (LWP 11192) exited]
[Thread 0x7fffe0bbc700 (LWP 11191) exited]
[Thread 0x7fffe23fd700 (LWP 11189) exited]
[Thread 0x7fffe2bfe700 (LWP 11188) exited]
[Thread 0x7fffd9189700 (LWP 11202) exited]
[Thread 0x7fffd8187700 (LWP 11204) exited]
[Thread 0x7fffd8988700 (LWP 11203) exited]
[Thread 0x7fffd998a700 (LWP 11201) exited]
[Thread 0x7fffda18b700 (LWP 11200) exited]
[Thread 0x7fffdb18d700 (LWP 11198) exited]
[Thread 0x7ffff7fac740 (LWP 11137) exited]
[Inferior 1 (process 11137) exited normally]
(gdb) quit
 1:16.29 INFO | runtests.py | exit 0
 1:16.29 INFO | runtests.py | Application ran for: 0:01:14.770105
 1:16.30 INFO | zombiecheck | Reading PID log: /tmp/tmp5COfvjpidlog
 1:16.30 WARNING | leakcheck | refcount logging is off, so leaks can't be detected!
 1:16.30 runtests.py | Running tests: end.
│currentpatch

Comment 6

[Ivan Alagenchev :ialagenchev]

I applied the patch and still get the same error:

alagenchev@tangra$ ~/mozilla-central/mach mochitest-browser --debugger=gdb *block_mixed*
 0:02.34 MochitestServer : launching [u'/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/dist/bin/xpcshell', '-g', u'/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/dist/bin', '-v', '170', '-f', '/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpt3VZKz'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', './server.js']
 0:02.34 runtests.py | Server pid: 5333
 0:03.35 runtests.py | Websocket server pid: 5348
 0:03.35 runtests.py | Running tests: start.

pk12util: PKCS12 IMPORT SUCCESSFUL
 0:03.44 INFO | runtests.py | SSL tunnel pid: 5368
 0:03.44 INFO | runtests.py | Application pid: 5370
Traceback (most recent call last):
  File "/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/runtests.py", line 1046, in runTests
    onLaunch=onLaunch
  File "/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/runtests.py", line 912, in runApp
    outputHandler.setProcess(proc, timeout)
  File "/home/alagenchev/mozilla-central/obj-x86_64-unknown-linux-gnu/_tests/testing/mochitest/runtests.py", line 1156, in setProcess
    stdin=proc.proc.stdout,
AttributeError: 'Popen' object has no attribute 'proc'
 0:03.44 Automation Error: Received unexpected exception while running application

failed to bind socket
Shutting down...
 0:03.46 WARNING | leakcheck | refcount logging is off, so leaks can't be detected!
 0:03.46 runtests.py | Running tests: end.

The problem is caused by this line:

self.stackFixerProcess = mozprocess.ProcessHandler(self.stackFixerCommand,
                                                           stdin=proc.proc.stdout,
                                                           processOutputLine=[self],
I don't know enough about the code to fix it myself, but I can provide you with any info that you might need. This is with python 2.7 on linux.

Comment 7

[(not currently active) Ted Mielczarek]

Ah, that's on a debug build, so it's trying to use the stack fixing code as well.

Comment 8

[Jeff Hammel]

Attachment: hopefully fix the fix

Ivan, could you try this patch and let me know if it helps?  If all is good, back up for Ted's review.

Comment 9

[Ivan Alagenchev :ialagenchev]

(In reply to Jeff Hammel [:jhammel] from comment #8)
> Created attachment 810751 [details] [diff] [review]
> hopefully fix the fix
> 
> Ivan, could you try this patch and let me know if it helps?  If all is good,
> back up for Ted's review.

It looks better. Now I'm getting the same message that I have in the description of the bug: https://bugzilla.mozilla.org/show_bug.cgi?id=920728#c0
Of course, that might be a separate issue. I will try to see if I'm getting that on a Mac too.

Comment 10

[Ivan Alagenchev :ialagenchev]

It fails with the following message under Mac:
### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/b8/2fkm7w0n52x2mc_3_3cd4vy00000gn/T/tmpiI7iXP/runtests_leaks.log

Program exited with code 06.
(gdb)

Comment 11

[Ivan Alagenchev :ialagenchev]

(In reply to Ivan Alagenchev :ialagenchev from comment #10)
> It fails with the following message under Mac:
> ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to
> /var/folders/b8/2fkm7w0n52x2mc_3_3cd4vy00000gn/T/tmpiI7iXP/runtests_leaks.log
> 
> Program exited with code 06.
> (gdb)

Sorry, the above isn't super clear. The gdb prompt appears fine on mac, but doing run results in the above error.

Comment 12

[(not currently active) Ted Mielczarek]

The Mac issue is bug 914925.

Comment 13

[Ivan Alagenchev :ialagenchev]

The latest patch + removing set follow-fork-mode child from my gdbinit fixed the problem for me on linux.

Comment 14

[Jeff Hammel]

pushed: https://hg.mozilla.org/integration/mozilla-inbound/rev/fbba0bcd49f0

Comment 15

[Ed Morley [:emorley]]

https://hg.mozilla.org/mozilla-central/rev/fbba0bcd49f0