" line: 0}]
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtCenter -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouse -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtPoint on body -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtPoint on button -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtCenter with complex selector -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtCenter mousedown with complex selector -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtCenter mousedown with complex selector not cancelled -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtCenter mouseup with complex selector -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | synthesizeMouseAtCenter mouseup with complex selector cancelled -
13:50:48 INFO - Leaving test bound
13:50:48 INFO - Entering test bound
13:50:48 INFO - Console message: [JavaScript Error: "NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIIOService2.newURI]" {file: "chrome://mochikit/content/tests/BrowserTestUtils/content-about-page-utils.js" line: 25}]
13:50:48 INFO - Console message: [JavaScript Error: "NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIIOService2.newURI]" {file: "chrome://mochikit/content/tests/BrowserTestUtils/content-about-page-utils.js" line: 25}]
13:50:48 INFO - Console message: [JavaScript Error: "[Exception... "Component returned failure code: 0x80570001 (NS_ERROR_XPC_NOT_ENOUGH_ARGS) [nsIWebNavigation.loadURIWithOptions]" nsresult: "0x80570001 (NS_ERROR_XPC_NOT_ENOUGH_ARGS)" location: "JS frame :: chrome://browser/content/browser.js :: _loadURIWithFlags :: line 848" data: no]"]
13:50:48 INFO - _loadURIWithFlags@chrome://browser/content/browser.js:848:7
13:50:48 INFO - loadURIWithFlags@chrome://browser/content/tabbrowser.xml:7370:13
13:50:48 INFO - addTab@chrome://browser/content/tabbrowser.xml:2274:17
13:50:48 INFO - openNewForegroundTab/promises<@resource://testing-common/BrowserTestUtils.jsm:121:42
13:50:48 INFO - switchTab@resource://testing-common/BrowserTestUtils.jsm:157:7
13:50:48 INFO - openNewForegroundTab@resource://testing-common/BrowserTestUtils.jsm:115:7
13:50:48 INFO - @chrome://mochitests/content/browser/testing/mochitest/tests/browser/browser_BrowserTestUtils.js:55:19
13:50:48 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:319:42
13:50:48 INFO -
13:50:48 INFO - Buffered messages finished
13:50:48 INFO - TEST-UNEXPECTED-FAIL | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | Test timed out -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | I'm a cleanup function in head file -
13:50:48 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | Head cleanup function scope is correct -
13:50:48 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
13:50:48 INFO - MEMORY STAT | vsize 3204MB | residentFast 273MB | heapAllocated 82MB
13:50:48 INFO - TEST-OK | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | took 45057ms
13:50:48 INFO - Not taking screenshot here: see the one that was previously logged
13:50:48 INFO - TEST-UNEXPECTED-FAIL | testing/mochitest/tests/browser/browser_BrowserTestUtils.js | Found a tab after previous test timed out: about:blank -
13:50:48 INFO - checking window state
13:50:48 INFO - TEST-START | testing/mochitest/tests/browser/browser_add_task.js
13:50:48 INFO - MEMORY STAT | vsize 3205MB | residentFast 275MB | heapAllocated 82MB
13:50:48 INFO - TEST-OK | testing/mochitest/tests/browser/browser_add_task.js | took 57ms
13:50:48 INFO - checking window state
13:50:48 INFO - TEST-START | testing/mochitest/tests/browser/browser_async.js
13:50:58 INFO - MEMORY STAT | vsize 3201MB | residentFast 272MB | heapAllocated 82MB
13:50:58 INFO - TEST-OK | testing/mochitest/tests/browser/browser_async.js | took 10023ms
13:50:58 INFO - checking window state
13:50:58 INFO - TEST-START | testing/mochitest/tests/browser/browser_browserLoaded_content_loaded.js
13:50:58 INFO - MEMORY STAT | vsize 3207MB | residentFast 278MB | heapAllocated 85MB
13:50:58 INFO - TEST-OK | testing/mochitest/tests/browser/browser_browserLoaded_content_loaded.js | took 495ms
13:50:58 INFO - checking window state
13:50:58 INFO - TEST-START | testing/mochitest/tests/browser/browser_fail_fp.js
13:50:58 INFO - MEMORY STAT | vsize 3207MB | residentFast 278MB | heapAllocated 86MB
13:50:58 INFO - TEST-OK | testing/mochitest/tests/browser/browser_fail_fp.js | took 53ms
13:50:58 INFO - checking window state
13:50:58 INFO - TEST-START | testing/mochitest/tests/browser/browser_fail_pf.js
13:50:58 INFO - MEMORY STAT | vsize 3207MB | residentFast 278MB | heapAllocated 86MB
13:50:58 INFO - TEST-OK | testing/mochitest/tests/browser/browser_fail_pf.js | took 51ms
13:50:58 INFO - checking window state
13:50:58 INFO - TEST-START | testing/mochitest/tests/browser/browser_getTestFile.js
13:50:58 INFO - TEST-PASS | unknown test url | getTestFilePath rejects absolute paths
13:50:59 INFO - MEMORY STAT | vsize 3207MB | residentFast 278MB | heapAllocated 87MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_getTestFile.js | took 70ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_head.js
13:50:59 INFO - MEMORY STAT | vsize 3207MB | residentFast 278MB | heapAllocated 87MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_head.js | took 38ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_parameters.js
13:50:59 INFO - MEMORY STAT | vsize 3207MB | residentFast 278MB | heapAllocated 88MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_parameters.js | took 31ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_pass.js
13:50:59 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_pass.js | pass ok -
13:50:59 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_pass.js | pass is -
13:50:59 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_pass.js | pass isnot -
13:50:59 INFO - TEST-FAIL | testing/mochitest/tests/browser/browser_pass.js | pass todo -
13:50:59 INFO - TEST-FAIL | testing/mochitest/tests/browser/browser_pass.js | pass todo_is -
13:50:59 INFO - TEST-FAIL | testing/mochitest/tests/browser/browser_pass.js | pass todo_isnot -
13:50:59 INFO - info message
13:50:59 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_pass.js | pass indirect is -
13:50:59 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_pass.js | I'm a cleanup function in head file -
13:50:59 INFO - TEST-PASS | testing/mochitest/tests/browser/browser_pass.js | Head cleanup function scope is correct -
13:50:59 INFO - MEMORY STAT | vsize 3207MB | residentFast 279MB | heapAllocated 89MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_pass.js | took 55ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_popupNode.js
13:50:59 INFO - MEMORY STAT | vsize 3207MB | residentFast 279MB | heapAllocated 89MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_popupNode.js | took 31ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_popupNode_check.js
13:50:59 INFO - MEMORY STAT | vsize 3209MB | residentFast 281MB | heapAllocated 90MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_popupNode_check.js | took 34ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_privileges.js
13:50:59 INFO - MEMORY STAT | vsize 3209MB | residentFast 281MB | heapAllocated 90MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_privileges.js | took 39ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_sanityException.js
13:50:59 INFO - MEMORY STAT | vsize 3211MB | residentFast 281MB | heapAllocated 91MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_sanityException.js | took 51ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_sanityException2.js
13:50:59 INFO - JavaScript error: , line 0: uncaught exception: this is a deliberately thrown exception
13:50:59 INFO - MEMORY STAT | vsize 3211MB | residentFast 281MB | heapAllocated 91MB
13:50:59 INFO - TEST-OK | testing/mochitest/tests/browser/browser_sanityException2.js | took 51ms
13:50:59 INFO - checking window state
13:50:59 INFO - TEST-START | testing/mochitest/tests/browser/browser_waitForFocus.js
13:51:00 INFO - MEMORY STAT | vsize 3220MB | residentFast 289MB | heapAllocated 98MB
13:51:00 INFO - TEST-OK | testing/mochitest/tests/browser/browser_waitForFocus.js | took 1005ms
13:51:00 INFO - checking window state
13:51:01 INFO - Completed ShutdownLeaks collections in process 1905
13:51:03 INFO - Completed ShutdownLeaks collections in process 1904
13:51:03 INFO - TEST-START | Shutdown
13:51:03 INFO - Browser Chrome Test Summary
13:51:03 INFO - Passed: 95
13:51:03 INFO - Failed: 2
13:51:03 INFO - Todo: 3
13:51:03 INFO - Mode: e10s
13:51:03 INFO - *** End BrowserChrome Test Results ***
13:51:03 INFO - -*- PresentationControlService.js: PresentationControlService - close
13:51:04 INFO - TEST-INFO | Main app process: exit 0
13:51:04 INFO - runtests.py | Application ran for: 0:01:04.910063
13:51:04 INFO - zombiecheck | Reading PID log: /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp425rsEpidlog
13:51:04 INFO - ==> process 1904 launched child process 1905
13:51:04 INFO - zombiecheck | Checking for orphan process with PID: 1905
13:51:04 INFO - Stopping web server
13:51:04 INFO - Stopping web socket server
13:51:04 INFO - Stopping ssltunnel
13:51:04 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
13:51:04 INFO - runtests.py | Running tests: end.
13:51:04 INFO - Buffered messages finished
13:51:04 INFO - dir: toolkit/components/passwordmgr/test/browser
13:51:04 INFO - mozprofile.addons WARNING | Could not install /builds/slave/test/build/tests/mochitest/extensions/mozscreenshots: [Errno 2] No such file or directory: '/builds/slave/test/build/tests/mochitest/extensions/mozscreenshots/install.rdf'
13:51:04 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
13:51:04 INFO - MochitestServer : launching [u'/builds/slave/test/build/tests/bin/xpcshell', '-g', '/builds/slave/test/build/application/Firefox.app/Contents/Resources', '-v', '170', '-f', '/builds/slave/test/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpQ3QZhY.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/slave/test/build/tests/mochitest/server.js']
13:51:04 INFO - runtests.py | Server pid: 1913
13:51:04 INFO - runtests.py | Websocket server pid: 1914
13:51:04 INFO - runtests.py | SSL tunnel pid: 1915
13:51:04 INFO - runtests.py | Running with e10s: True
13:51:04 INFO - runtests.py | Running tests: start.
13:51:04 INFO -
13:51:04 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -marionette -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpQ3QZhY.mozrunner
13:51:04 INFO - runtests.py | Application pid: 1916
13:51:04 INFO - TEST-INFO | started process Main app process
13:51:05 INFO - 1486590665335 addons.xpi WARN Add-on mozscreenshots@mozilla.org is not correctly signed.
13:51:05 INFO - 1486590665335 addons.xpi WARN Refusing to install staged add-on mozscreenshots@mozilla.org with signed state 0
13:51:06 INFO - 1486590666067 Marionette INFO Listening on port 2828
13:51:08 INFO - 2017-02-08 13:51:08.155 plugin-container[1917:13056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname'
13:51:08 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:51:08 INFO - 2017-02-08 13:51:08.156 plugin-container[1917:13056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient'
13:51:08 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:51:08 INFO - 2017-02-08 13:51:08.156 plugin-container[1917:13056] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
13:51:08 INFO - *** Start BrowserChrome Test Results ***
13:51:08 INFO - checking window state
13:51:08 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_DOMFormHasPassword.js
13:51:09 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
13:51:09 INFO - MEMORY STAT | vsize 3009MB | residentFast 269MB | heapAllocated 128MB
13:51:09 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_DOMFormHasPassword.js | took 331ms
13:51:09 INFO - checking window state
13:51:09 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_DOMInputPasswordAdded.js
13:51:09 INFO - MEMORY STAT | vsize 3019MB | residentFast 275MB | heapAllocated 131MB
13:51:09 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_DOMInputPasswordAdded.js | took 189ms
13:51:09 INFO - checking window state
13:51:09 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_autocomplete_insecure_warning.js
13:51:10 INFO - MEMORY STAT | vsize 3041MB | residentFast 297MB | heapAllocated 137MB
13:51:10 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_autocomplete_insecure_warning.js | took 837ms
13:51:10 INFO - checking window state
13:51:10 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_capture_doorhanger.js
13:51:24 INFO - MEMORY STAT | vsize 3261MB | residentFast 331MB | heapAllocated 119MB
13:51:24 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_capture_doorhanger.js | took 14263ms
13:51:24 INFO - checking window state
13:51:24 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_capture_doorhanger_httpsUpgrade.js
13:51:26 INFO - console.warn: nsLoginManager: searchLogins: `formSubmitURL` or `httpRealm` is recommended
13:51:26 INFO - console.warn: nsLoginManager: searchLogins: `formSubmitURL` or `httpRealm` is recommended
13:51:26 INFO - MEMORY STAT | vsize 3253MB | residentFast 330MB | heapAllocated 104MB
13:51:26 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_capture_doorhanger_httpsUpgrade.js | took 2259ms
13:51:26 INFO - checking window state
13:51:26 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_capture_doorhanger_window_open.js
13:51:30 INFO - MEMORY STAT | vsize 3284MB | residentFast 355MB | heapAllocated 126MB
13:51:30 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_capture_doorhanger_window_open.js | took 3797ms
13:51:30 INFO - checking window state
13:51:30 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_context_menu_iframe.js
13:51:31 INFO - console.warn: nsLoginManager: searchLogins: `formSubmitURL` or `httpRealm` is recommended
13:51:31 INFO - MEMORY STAT | vsize 3289MB | residentFast 356MB | heapAllocated 131MB
13:51:31 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_context_menu_iframe.js | took 657ms
13:51:31 INFO - checking window state
13:51:31 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_exceptions_dialog.js
13:51:31 INFO - MEMORY STAT | vsize 3291MB | residentFast 358MB | heapAllocated 133MB
13:51:31 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_exceptions_dialog.js | took 419ms
13:51:31 INFO - checking window state
13:51:31 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_formless_submit_chrome.js
13:51:35 INFO - MEMORY STAT | vsize 3277MB | residentFast 354MB | heapAllocated 117MB
13:51:35 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_formless_submit_chrome.js | took 3357ms
13:51:35 INFO - checking window state
13:51:35 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_hasInsecureLoginForms.js
13:51:36 INFO - MEMORY STAT | vsize 3278MB | residentFast 355MB | heapAllocated 120MB
13:51:36 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_hasInsecureLoginForms.js | took 1162ms
13:51:36 INFO - checking window state
13:51:36 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_hasInsecureLoginForms_streamConverter.js
13:51:36 INFO - MEMORY STAT | vsize 3278MB | residentFast 356MB | heapAllocated 121MB
13:51:36 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_hasInsecureLoginForms_streamConverter.js | took 421ms
13:51:36 INFO - checking window state
13:51:36 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_http_autofill.js
13:51:39 INFO - MEMORY STAT | vsize 3277MB | residentFast 355MB | heapAllocated 113MB
13:51:39 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_http_autofill.js | took 2166ms
13:51:39 INFO - checking window state
13:51:39 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_insecurePasswordConsoleWarning.js
13:51:44 INFO - MEMORY STAT | vsize 3282MB | residentFast 358MB | heapAllocated 123MB
13:51:44 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_insecurePasswordConsoleWarning.js | took 5263ms
13:51:44 INFO - checking window state
13:51:44 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_notifications.js
13:51:46 INFO - MEMORY STAT | vsize 3279MB | residentFast 358MB | heapAllocated 117MB
13:51:46 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_notifications.js | took 2417ms
13:51:46 INFO - checking window state
13:51:46 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_notifications_2.js
13:51:47 INFO - JavaScript error: jar:file:///builds/slave/test/build/application/Firefox.app/Contents/Resources/omni.ja!/components/nsLoginManager.js, line 284: Error: Can't add a login with a null or empty password.
13:51:48 INFO - MP change from to omgsecret!
13:51:48 INFO - MP change from omgsecret! to
13:51:48 INFO - MEMORY STAT | vsize 3280MB | residentFast 359MB | heapAllocated 120MB
13:51:48 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_notifications_2.js | took 1783ms
13:51:48 INFO - checking window state
13:51:48 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_notifications_password.js
13:51:51 INFO - MEMORY STAT | vsize 3277MB | residentFast 357MB | heapAllocated 113MB
13:51:51 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_notifications_password.js | took 2887ms
13:51:51 INFO - checking window state
13:51:51 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_notifications_username.js
13:51:56 INFO - MEMORY STAT | vsize 3281MB | residentFast 360MB | heapAllocated 124MB
13:51:56 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_notifications_username.js | took 4580ms
13:51:56 INFO - checking window state
13:51:56 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_editing.js
13:51:59 INFO - MEMORY STAT | vsize 3278MB | residentFast 353MB | heapAllocated 107MB
13:51:59 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_editing.js | took 3396ms
13:51:59 INFO - checking window state
13:51:59 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_fields.js
13:51:59 INFO - MEMORY STAT | vsize 3276MB | residentFast 351MB | heapAllocated 108MB
13:51:59 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_fields.js | took 235ms
13:51:59 INFO - checking window state
13:51:59 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_observers.js
13:52:00 INFO - MEMORY STAT | vsize 3277MB | residentFast 352MB | heapAllocated 110MB
13:52:00 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_observers.js | took 274ms
13:52:00 INFO - checking window state
13:52:00 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_sort.js
13:52:01 INFO - JavaScript error: chrome://passwordmgr/content/passwordManager.js, line 234: TypeError: table[selections[0]] is undefined
13:52:01 INFO - MEMORY STAT | vsize 3279MB | residentFast 355MB | heapAllocated 112MB
13:52:01 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_sort.js | took 883ms
13:52:01 INFO - checking window state
13:52:01 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_switchtab.js
13:52:02 INFO - MEMORY STAT | vsize 3263MB | residentFast 344MB | heapAllocated 113MB
13:52:02 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_passwordmgr_switchtab.js | took 894ms
13:52:02 INFO - checking window state
13:52:02 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_passwordmgrdlg.js
13:52:12 INFO - MEMORY STAT | vsize 3275MB | residentFast 347MB | heapAllocated 117MB
13:52:12 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_passwordmgrdlg.js | took 10029ms
13:52:12 INFO - checking window state
13:52:12 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_username_select_dialog.js
13:52:14 INFO - MEMORY STAT | vsize 3267MB | residentFast 350MB | heapAllocated 110MB
13:52:14 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_username_select_dialog.js | took 2467ms
13:52:14 INFO - checking window state
13:52:15 INFO - Completed ShutdownLeaks collections in process 1917
13:52:17 INFO - Completed ShutdownLeaks collections in process 1916
13:52:17 INFO - TEST-START | Shutdown
13:52:17 INFO - Browser Chrome Test Summary
13:52:17 INFO - Passed: 867
13:52:17 INFO - Failed: 0
13:52:17 INFO - Todo: 0
13:52:17 INFO - Mode: e10s
13:52:17 INFO - *** End BrowserChrome Test Results ***
13:52:17 INFO - -*- PresentationControlService.js: PresentationControlService - close
13:52:18 INFO - TEST-INFO | Main app process: exit 0
13:52:18 INFO - runtests.py | Application ran for: 0:01:13.736278
13:52:18 INFO - zombiecheck | Reading PID log: /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpXNBxoypidlog
13:52:18 INFO - ==> process 1916 launched child process 1917
13:52:18 INFO - zombiecheck | Checking for orphan process with PID: 1917
13:52:18 INFO - Stopping web server
13:52:18 INFO - Stopping web socket server
13:52:18 INFO - Stopping ssltunnel
13:52:18 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
13:52:18 INFO - runtests.py | Running tests: end.
13:52:18 INFO - Buffered messages finished
13:52:18 INFO - dir: toolkit/components/reader/test
13:52:18 INFO - mozprofile.addons WARNING | Could not install /builds/slave/test/build/tests/mochitest/extensions/mozscreenshots: [Errno 2] No such file or directory: '/builds/slave/test/build/tests/mochitest/extensions/mozscreenshots/install.rdf'
13:52:18 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
13:52:18 INFO - MochitestServer : launching [u'/builds/slave/test/build/tests/bin/xpcshell', '-g', '/builds/slave/test/build/application/Firefox.app/Contents/Resources', '-v', '170', '-f', '/builds/slave/test/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpsJaQmh.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/slave/test/build/tests/mochitest/server.js']
13:52:18 INFO - runtests.py | Server pid: 1924
13:52:18 INFO - runtests.py | Websocket server pid: 1925
13:52:18 INFO - runtests.py | SSL tunnel pid: 1926
13:52:18 INFO - runtests.py | Running with e10s: True
13:52:18 INFO - runtests.py | Running tests: start.
13:52:18 INFO -
13:52:18 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -marionette -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpsJaQmh.mozrunner
13:52:18 INFO - runtests.py | Application pid: 1927
13:52:18 INFO - TEST-INFO | started process Main app process
13:52:19 INFO - 1486590739570 addons.xpi WARN Add-on mozscreenshots@mozilla.org is not correctly signed.
13:52:19 INFO - 1486590739571 addons.xpi WARN Refusing to install staged add-on mozscreenshots@mozilla.org with signed state 0
13:52:20 INFO - 1486590740467 Marionette INFO Listening on port 2828
13:52:22 INFO - 2017-02-08 13:52:22.625 plugin-container[1928:13401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname'
13:52:22 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:22 INFO - 2017-02-08 13:52:22.626 plugin-container[1928:13401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8447, name = 'com.apple.CFPasteboardClient'
13:52:22 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:22 INFO - 2017-02-08 13:52:22.626 plugin-container[1928:13401] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
13:52:23 INFO - *** Start BrowserChrome Test Results ***
13:52:23 INFO - checking window state
13:52:23 INFO - TEST-START | toolkit/components/reader/test/browser_bug1124271_readerModePinnedTab.js
13:52:23 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
13:52:23 INFO - MEMORY STAT | vsize 3032MB | residentFast 281MB | heapAllocated 130MB
13:52:23 INFO - TEST-OK | toolkit/components/reader/test/browser_bug1124271_readerModePinnedTab.js | took 556ms
13:52:23 INFO - checking window state
13:52:23 INFO - TEST-START | toolkit/components/reader/test/browser_readerMode.js
13:52:24 INFO - JavaScript error: resource://gre/modules/narrate/NarrateControls.jsm, line 200: Error: Not a string
13:52:25 INFO - JavaScript error: resource://gre/modules/narrate/NarrateControls.jsm, line 200: Error: Not a string
13:52:25 INFO - MEMORY STAT | vsize 3098MB | residentFast 351MB | heapAllocated 148MB
13:52:25 INFO - TEST-OK | toolkit/components/reader/test/browser_readerMode.js | took 1834ms
13:52:25 INFO - checking window state
13:52:25 INFO - TEST-START | toolkit/components/reader/test/browser_readerMode_hidden_nodes.js
13:52:25 INFO - MEMORY STAT | vsize 3098MB | residentFast 351MB | heapAllocated 148MB
13:52:25 INFO - TEST-OK | toolkit/components/reader/test/browser_readerMode_hidden_nodes.js | took 213ms
13:52:25 INFO - checking window state
13:52:25 INFO - TEST-START | toolkit/components/reader/test/browser_readerMode_with_anchor.js
13:52:26 INFO - JavaScript error: resource://gre/modules/narrate/NarrateControls.jsm, line 200: Error: Not a string
13:52:26 INFO - MEMORY STAT | vsize 3110MB | residentFast 363MB | heapAllocated 150MB
13:52:26 INFO - TEST-OK | toolkit/components/reader/test/browser_readerMode_with_anchor.js | took 475ms
13:52:26 INFO - checking window state
13:52:27 INFO - Completed ShutdownLeaks collections in process 1928
13:52:29 INFO - Completed ShutdownLeaks collections in process 1927
13:52:29 INFO - TEST-START | Shutdown
13:52:29 INFO - Browser Chrome Test Summary
13:52:29 INFO - Passed: 41
13:52:29 INFO - Failed: 0
13:52:29 INFO - Todo: 0
13:52:29 INFO - Mode: e10s
13:52:29 INFO - *** End BrowserChrome Test Results ***
13:52:29 INFO - -*- PresentationControlService.js: PresentationControlService - close
13:52:30 INFO - TEST-INFO | Main app process: exit 0
13:52:30 INFO - runtests.py | Application ran for: 0:00:11.121485
13:52:30 INFO - zombiecheck | Reading PID log: /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpRVwJY8pidlog
13:52:30 INFO - ==> process 1927 launched child process 1928
13:52:30 INFO - zombiecheck | Checking for orphan process with PID: 1928
13:52:30 INFO - Stopping web server
13:52:30 INFO - Stopping web socket server
13:52:30 INFO - Stopping ssltunnel
13:52:30 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
13:52:30 INFO - runtests.py | Running tests: end.
13:52:30 INFO - Buffered messages finished
13:52:30 INFO - dir: toolkit/components/telemetry/tests/browser
13:52:30 INFO - mozprofile.addons WARNING | Could not install /builds/slave/test/build/tests/mochitest/extensions/mozscreenshots: [Errno 2] No such file or directory: '/builds/slave/test/build/tests/mochitest/extensions/mozscreenshots/install.rdf'
13:52:30 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
13:52:30 INFO - MochitestServer : launching [u'/builds/slave/test/build/tests/bin/xpcshell', '-g', '/builds/slave/test/build/application/Firefox.app/Contents/Resources', '-v', '170', '-f', '/builds/slave/test/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpYGjSHX.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/slave/test/build/tests/mochitest/server.js']
13:52:30 INFO - runtests.py | Server pid: 1935
13:52:30 INFO - runtests.py | Websocket server pid: 1936
13:52:30 INFO - runtests.py | SSL tunnel pid: 1937
13:52:30 INFO - runtests.py | Running with e10s: True
13:52:30 INFO - runtests.py | Running tests: start.
13:52:30 INFO -
13:52:30 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -marionette -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpYGjSHX.mozrunner
13:52:30 INFO - runtests.py | Application pid: 1938
13:52:30 INFO - TEST-INFO | started process Main app process
13:52:31 INFO - 1486590751283 addons.xpi WARN Add-on mozscreenshots@mozilla.org is not correctly signed.
13:52:31 INFO - 1486590751284 addons.xpi WARN Refusing to install staged add-on mozscreenshots@mozilla.org with signed state 0
13:52:32 INFO - 1486590752046 Marionette INFO Listening on port 2828
13:52:33 INFO - 2017-02-08 13:52:33.974 plugin-container[1939:13644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.tsm.portname'
13:52:33 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:33 INFO - 2017-02-08 13:52:33.975 plugin-container[1939:13644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a17, name = 'com.apple.CFPasteboardClient'
13:52:33 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:33 INFO - 2017-02-08 13:52:33.975 plugin-container[1939:13644] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
13:52:34 INFO - *** Start BrowserChrome Test Results ***
13:52:34 INFO - checking window state
13:52:34 INFO - TEST-START | toolkit/components/telemetry/tests/browser/browser_TelemetryGC.js
13:52:35 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
13:52:35 INFO - MEMORY STAT | vsize 3010MB | residentFast 255MB | heapAllocated 102MB
13:52:35 INFO - TEST-OK | toolkit/components/telemetry/tests/browser/browser_TelemetryGC.js | took 427ms
13:52:35 INFO - checking window state
13:52:35 INFO - Completed ShutdownLeaks collections in process 1939
13:52:37 INFO - Completed ShutdownLeaks collections in process 1938
13:52:37 INFO - TEST-START | Shutdown
13:52:37 INFO - Browser Chrome Test Summary
13:52:37 INFO - Passed: 685
13:52:37 INFO - Failed: 0
13:52:37 INFO - Todo: 0
13:52:37 INFO - Mode: e10s
13:52:37 INFO - *** End BrowserChrome Test Results ***
13:52:37 INFO - -*- PresentationControlService.js: PresentationControlService - close
13:52:38 INFO - TEST-INFO | Main app process: exit 0
13:52:38 INFO - runtests.py | Application ran for: 0:00:07.392003
13:52:38 INFO - zombiecheck | Reading PID log: /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpNidKlfpidlog
13:52:38 INFO - ==> process 1938 launched child process 1939
13:52:38 INFO - zombiecheck | Checking for orphan process with PID: 1939
13:52:38 INFO - Stopping web server
13:52:38 INFO - Stopping web socket server
13:52:38 INFO - Stopping ssltunnel
13:52:38 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
13:52:38 INFO - runtests.py | Running tests: end.
13:52:38 INFO - Buffered messages finished
13:52:38 INFO - dir: toolkit/components/viewsource/test/browser
13:52:38 INFO - mozprofile.addons WARNING | Could not install /builds/slave/test/build/tests/mochitest/extensions/mozscreenshots: [Errno 2] No such file or directory: '/builds/slave/test/build/tests/mochitest/extensions/mozscreenshots/install.rdf'
13:52:38 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
13:52:38 INFO - MochitestServer : launching [u'/builds/slave/test/build/tests/bin/xpcshell', '-g', '/builds/slave/test/build/application/Firefox.app/Contents/Resources', '-v', '170', '-f', '/builds/slave/test/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpetW2Zw.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/slave/test/build/tests/mochitest/server.js']
13:52:38 INFO - runtests.py | Server pid: 1946
13:52:38 INFO - runtests.py | Websocket server pid: 1947
13:52:38 INFO - runtests.py | SSL tunnel pid: 1948
13:52:38 INFO - runtests.py | Running with e10s: True
13:52:38 INFO - runtests.py | Running tests: start.
13:52:38 INFO -
13:52:38 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -marionette -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpetW2Zw.mozrunner
13:52:38 INFO - runtests.py | Application pid: 1949
13:52:38 INFO - TEST-INFO | started process Main app process
13:52:39 INFO - 1486590759181 addons.xpi WARN Add-on mozscreenshots@mozilla.org is not correctly signed.
13:52:39 INFO - 1486590759181 addons.xpi WARN Refusing to install staged add-on mozscreenshots@mozilla.org with signed state 0
13:52:40 INFO - 1486590760109 Marionette INFO Listening on port 2828
13:52:42 INFO - 2017-02-08 13:52:42.030 plugin-container[1950:13842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname'
13:52:42 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:42 INFO - 2017-02-08 13:52:42.031 plugin-container[1950:13842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient'
13:52:42 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:42 INFO - 2017-02-08 13:52:42.031 plugin-container[1950:13842] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
13:52:42 INFO - *** Start BrowserChrome Test Results ***
13:52:42 INFO - checking window state
13:52:42 INFO - TEST-START | toolkit/components/viewsource/test/browser/browser_bug464222.js
13:52:43 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
13:52:43 INFO - MEMORY STAT | vsize 3034MB | residentFast 294MB | heapAllocated 136MB
13:52:43 INFO - TEST-OK | toolkit/components/viewsource/test/browser/browser_bug464222.js | took 929ms
13:52:43 INFO - checking window state
13:52:43 INFO - TEST-START | toolkit/components/viewsource/test/browser/browser_bug699356.js
13:52:43 INFO - MEMORY STAT | vsize 3056MB | residentFast 308MB | heapAllocated 142MB
13:52:43 INFO - TEST-OK | toolkit/components/viewsource/test/browser/browser_bug699356.js | took 337ms
13:52:43 INFO - checking window state
13:52:43 INFO - TEST-START | toolkit/components/viewsource/test/browser/browser_bug713810.js
13:52:45 INFO - MEMORY STAT | vsize 3070MB | residentFast 327MB | heapAllocated 147MB
13:52:45 INFO - TEST-OK | toolkit/components/viewsource/test/browser/browser_bug713810.js | took 1265ms
13:52:45 INFO - checking window state
13:52:45 INFO - TEST-START | toolkit/components/viewsource/test/browser/browser_gotoline.js
13:52:46 INFO - MEMORY STAT | vsize 3098MB | residentFast 345MB | heapAllocated 154MB
13:52:46 INFO - TEST-OK | toolkit/components/viewsource/test/browser/browser_gotoline.js | took 1218ms
13:52:46 INFO - checking window state
13:52:46 INFO - TEST-START | toolkit/components/viewsource/test/browser/browser_srcdoc.js
13:52:47 INFO - MEMORY STAT | vsize 3101MB | residentFast 350MB | heapAllocated 158MB
13:52:47 INFO - TEST-OK | toolkit/components/viewsource/test/browser/browser_srcdoc.js | took 662ms
13:52:47 INFO - checking window state
13:52:47 INFO - TEST-START | toolkit/components/viewsource/test/browser/browser_viewsourceprefs.js
13:52:48 INFO - MEMORY STAT | vsize 3336MB | residentFast 385MB | heapAllocated 174MB
13:52:48 INFO - TEST-OK | toolkit/components/viewsource/test/browser/browser_viewsourceprefs.js | took 1204ms
13:52:48 INFO - checking window state
13:52:49 INFO - Completed ShutdownLeaks collections in process 1950
13:52:51 INFO - Completed ShutdownLeaks collections in process 1949
13:52:51 INFO - TEST-START | Shutdown
13:52:51 INFO - Browser Chrome Test Summary
13:52:51 INFO - Passed: 65
13:52:51 INFO - Failed: 0
13:52:51 INFO - Todo: 0
13:52:51 INFO - Mode: e10s
13:52:51 INFO - *** End BrowserChrome Test Results ***
13:52:52 INFO - -*- PresentationControlService.js: PresentationControlService - close
13:52:52 INFO - TEST-INFO | Main app process: exit 0
13:52:52 INFO - runtests.py | Application ran for: 0:00:13.839912
13:52:52 INFO - zombiecheck | Reading PID log: /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpineMh_pidlog
13:52:52 INFO - ==> process 1949 launched child process 1950
13:52:52 INFO - zombiecheck | Checking for orphan process with PID: 1950
13:52:52 INFO - Stopping web server
13:52:52 INFO - Stopping web socket server
13:52:52 INFO - Stopping ssltunnel
13:52:52 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
13:52:52 INFO - runtests.py | Running tests: end.
13:52:52 INFO - Buffered messages finished
13:52:52 INFO - dir: toolkit/crashreporter/test/browser
13:52:52 INFO - mozprofile.addons WARNING | Could not install /builds/slave/test/build/tests/mochitest/extensions/mozscreenshots: [Errno 2] No such file or directory: '/builds/slave/test/build/tests/mochitest/extensions/mozscreenshots/install.rdf'
13:52:52 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
13:52:52 INFO - MochitestServer : launching [u'/builds/slave/test/build/tests/bin/xpcshell', '-g', '/builds/slave/test/build/application/Firefox.app/Contents/Resources', '-v', '170', '-f', '/builds/slave/test/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp_GYsas.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/slave/test/build/tests/mochitest/server.js']
13:52:52 INFO - runtests.py | Server pid: 1957
13:52:52 INFO - runtests.py | Websocket server pid: 1958
13:52:52 INFO - runtests.py | SSL tunnel pid: 1959
13:52:52 INFO - runtests.py | Running with e10s: True
13:52:52 INFO - runtests.py | Running tests: start.
13:52:52 INFO -
13:52:52 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -marionette -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp_GYsas.mozrunner
13:52:52 INFO - runtests.py | Application pid: 1960
13:52:52 INFO - TEST-INFO | started process Main app process
13:52:53 INFO - 1486590773617 addons.xpi WARN Add-on mozscreenshots@mozilla.org is not correctly signed.
13:52:53 INFO - 1486590773618 addons.xpi WARN Refusing to install staged add-on mozscreenshots@mozilla.org with signed state 0
13:52:54 INFO - 1486590774352 Marionette INFO Listening on port 2828
13:52:56 INFO - 2017-02-08 13:52:56.373 plugin-container[1961:14094] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname'
13:52:56 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:56 INFO - 2017-02-08 13:52:56.374 plugin-container[1961:14094] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient'
13:52:56 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:52:56 INFO - 2017-02-08 13:52:56.374 plugin-container[1961:14094] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
13:52:56 INFO - *** Start BrowserChrome Test Results ***
13:52:56 INFO - checking window state
13:52:57 INFO - TEST-START | toolkit/crashreporter/test/browser/browser_aboutCrashes.js
13:52:57 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
13:52:57 INFO - MEMORY STAT | vsize 3033MB | residentFast 281MB | heapAllocated 137MB
13:52:57 INFO - TEST-OK | toolkit/crashreporter/test/browser/browser_aboutCrashes.js | took 427ms
13:52:57 INFO - checking window state
13:52:57 INFO - TEST-START | toolkit/crashreporter/test/browser/browser_aboutCrashesResubmit.js
13:52:58 INFO - MEMORY STAT | vsize 3051MB | residentFast 306MB | heapAllocated 145MB
13:52:58 INFO - TEST-OK | toolkit/crashreporter/test/browser/browser_aboutCrashesResubmit.js | took 764ms
13:52:58 INFO - checking window state
13:52:58 INFO - TEST-START | toolkit/crashreporter/test/browser/browser_bug471404.js
13:52:58 INFO - cleanup_fake_appdir: dirSvc.undefine failed: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.undefine]
13:52:58 INFO - MEMORY STAT | vsize 3069MB | residentFast 325MB | heapAllocated 156MB
13:52:58 INFO - TEST-OK | toolkit/crashreporter/test/browser/browser_bug471404.js | took 673ms
13:52:58 INFO - checking window state
13:52:58 INFO - TEST-START | toolkit/crashreporter/test/browser/browser_clearReports.js
13:52:59 INFO - cleanup_fake_appdir: dirSvc.undefine failed: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIProperties.undefine]
13:52:59 INFO - MEMORY STAT | vsize 3077MB | residentFast 336MB | heapAllocated 160MB
13:52:59 INFO - TEST-OK | toolkit/crashreporter/test/browser/browser_clearReports.js | took 493ms
13:52:59 INFO - checking window state
13:53:00 INFO - Completed ShutdownLeaks collections in process 1961
13:53:02 INFO - Completed ShutdownLeaks collections in process 1960
13:53:02 INFO - TEST-START | Shutdown
13:53:02 INFO - Browser Chrome Test Summary
13:53:02 INFO - Passed: 33
13:53:02 INFO - Failed: 0
13:53:02 INFO - Todo: 0
13:53:02 INFO - Mode: e10s
13:53:02 INFO - *** End BrowserChrome Test Results ***
13:53:02 INFO - -*- PresentationControlService.js: PresentationControlService - close
13:53:02 INFO - TEST-INFO | Main app process: exit 0
13:53:02 INFO - runtests.py | Application ran for: 0:00:09.991906
13:53:02 INFO - zombiecheck | Reading PID log: /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpUVsOXJpidlog
13:53:02 INFO - ==> process 1960 launched child process 1961
13:53:02 INFO - zombiecheck | Checking for orphan process with PID: 1961
13:53:02 INFO - Stopping web server
13:53:02 INFO - Stopping web socket server
13:53:02 INFO - Stopping ssltunnel
13:53:02 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
13:53:02 INFO - runtests.py | Running tests: end.
13:53:02 INFO - Buffered messages finished
13:53:02 INFO - dir: toolkit/mozapps/extensions/test/xpinstall
13:53:03 INFO - mozprofile.addons WARNING | Could not install /builds/slave/test/build/tests/mochitest/extensions/mozscreenshots: [Errno 2] No such file or directory: '/builds/slave/test/build/tests/mochitest/extensions/mozscreenshots/install.rdf'
13:53:03 INFO - pk12util: PKCS12 IMPORT SUCCESSFUL
13:53:03 INFO - MochitestServer : launching [u'/builds/slave/test/build/tests/bin/xpcshell', '-g', '/builds/slave/test/build/application/Firefox.app/Contents/Resources', '-v', '170', '-f', '/builds/slave/test/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpgAfAVV.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/slave/test/build/tests/mochitest/server.js']
13:53:03 INFO - runtests.py | Server pid: 1968
13:53:03 INFO - runtests.py | Websocket server pid: 1969
13:53:03 INFO - runtests.py | SSL tunnel pid: 1970
13:53:03 INFO - runtests.py | Running with e10s: True
13:53:03 INFO - runtests.py | Running tests: start.
13:53:03 INFO -
13:53:03 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -marionette -foreground -profile /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmpgAfAVV.mozrunner
13:53:03 INFO - runtests.py | Application pid: 1971
13:53:03 INFO - TEST-INFO | started process Main app process
13:53:03 INFO - 1486590783992 addons.xpi WARN Add-on mozscreenshots@mozilla.org is not correctly signed.
13:53:03 INFO - 1486590783992 addons.xpi WARN Refusing to install staged add-on mozscreenshots@mozilla.org with signed state 0
13:53:04 INFO - 1486590784731 Marionette INFO Listening on port 2828
13:53:06 INFO - 2017-02-08 13:53:06.658 plugin-container[1972:14354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname'
13:53:06 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:53:06 INFO - 2017-02-08 13:53:06.658 plugin-container[1972:14354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient'
13:53:06 INFO - See /usr/include/servers/bootstrap_defs.h for the error codes.
13:53:06 INFO - 2017-02-08 13:53:06.658 plugin-container[1972:14354] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions
13:53:07 INFO - *** Start BrowserChrome Test Results ***
13:53:07 INFO - checking window state
13:53:07 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_amosigned_trigger.js
13:53:07 INFO - Sending InstallTriggered
13:53:07 INFO - 1486590787580 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-sca.xpi
13:53:07 INFO - 1486590787585 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:07 INFO - 1486590787607 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:08 INFO - 1486590788497 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:08 INFO - 1486590788594 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:08 INFO - 1486590788598 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:08 INFO - Sending InstallComplete
13:53:08 INFO - 1486590788607 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:08 INFO - 1486590788612 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-sca.xpi
13:53:08 INFO - MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
13:53:08 INFO - MEMORY STAT | vsize 3034MB | residentFast 282MB | heapAllocated 134MB
13:53:08 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_amosigned_trigger.js | took 1494ms
13:53:08 INFO - checking window state
13:53:08 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_amosigned_trigger_iframe.js
13:53:09 INFO - Sending InstallTriggered
13:53:09 INFO - 1486590789039 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ue9.xpi
13:53:09 INFO - 1486590789042 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:09 INFO - 1486590789060 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:09 INFO - 1486590789929 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:09 INFO - 1486590789971 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:09 INFO - 1486590789975 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:09 INFO - Sending InstallComplete
13:53:09 INFO - 1486590789980 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:09 INFO - 1486590789985 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ue9.xpi
13:53:10 INFO - MEMORY STAT | vsize 3044MB | residentFast 292MB | heapAllocated 138MB
13:53:10 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_amosigned_trigger_iframe.js | took 1281ms
13:53:10 INFO - checking window state
13:53:10 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_amosigned_url.js
13:53:10 INFO - 1486590790222 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-okn.xpi
13:53:10 INFO - 1486590790223 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:10 INFO - 1486590790241 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:11 INFO - 1486590791078 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:11 INFO - 1486590791146 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:11 INFO - 1486590791149 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:11 INFO - 1486590791155 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:11 INFO - 1486590791160 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-okn.xpi
13:53:11 INFO - MEMORY STAT | vsize 3051MB | residentFast 298MB | heapAllocated 141MB
13:53:11 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_amosigned_url.js | took 1132ms
13:53:11 INFO - checking window state
13:53:11 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_auth.js
13:53:11 INFO - Sending InstallTriggered
13:53:12 INFO - 1486590792261 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-o2f.xpi
13:53:12 INFO - 1486590792261 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:12 INFO - 1486590792273 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:13 INFO - 1486590793113 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:13 INFO - 1486590793175 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:13 INFO - 1486590793177 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:13 INFO - Sending InstallComplete
13:53:13 INFO - 1486590793183 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:13 INFO - 1486590793187 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-o2f.xpi
13:53:13 INFO - MEMORY STAT | vsize 3261MB | residentFast 309MB | heapAllocated 147MB
13:53:13 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_auth.js | took 1974ms
13:53:13 INFO - checking window state
13:53:13 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_auth2.js
13:53:13 INFO - Sending InstallTriggered
13:53:14 INFO - 1486590794170 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-m5t.xpi
13:53:14 INFO - 1486590794171 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:14 INFO - 1486590794171 addons.xpi WARN Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi failed: 403 Forbidden
13:53:14 INFO - Sending InstallComplete
13:53:14 INFO - 1486590794182 addons.xpi DEBUG downloadFailed: removing temp file for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:14 INFO - 1486590794182 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-m5t.xpi
13:53:14 INFO - MEMORY STAT | vsize 3265MB | residentFast 313MB | heapAllocated 151MB
13:53:14 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_auth2.js | took 1014ms
13:53:14 INFO - checking window state
13:53:14 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_auth3.js
13:53:14 INFO - Sending InstallTriggered
13:53:15 INFO - 1486590795579 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-23u.xpi
13:53:15 INFO - 1486590795580 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:15 INFO - 1486590795580 addons.xpi WARN Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi failed: 401 Authentication required
13:53:15 INFO - Sending InstallComplete
13:53:15 INFO - 1486590795592 addons.xpi DEBUG downloadFailed: removing temp file for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:15 INFO - 1486590795592 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-23u.xpi
13:53:15 INFO - MEMORY STAT | vsize 3251MB | residentFast 311MB | heapAllocated 106MB
13:53:15 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_auth3.js | took 1353ms
13:53:15 INFO - checking window state
13:53:15 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_auth4.js
13:53:15 INFO - Sending InstallTriggered
13:53:16 INFO - 1486590796832 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-4nb.xpi
13:53:16 INFO - 1486590796834 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:16 INFO - 1486590796860 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:17 INFO - 1486590797714 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:17 INFO - 1486590797796 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:17 INFO - 1486590797800 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:17 INFO - Sending InstallComplete
13:53:17 INFO - 1486590797806 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:17 INFO - 1486590797810 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/authRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-4nb.xpi
13:53:17 INFO - MEMORY STAT | vsize 3251MB | residentFast 309MB | heapAllocated 111MB
13:53:17 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_auth4.js | took 2132ms
13:53:17 INFO - checking window state
13:53:17 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_badargs.js
13:53:18 INFO - Sending InstallTriggered
13:53:18 INFO - JavaScript error: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%22http%3A%2F%2Fexample.com%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22, line 26: TypeError: Argument 1 of InstallTriggerImpl.install is not an object.
13:53:18 INFO - MEMORY STAT | vsize 3251MB | residentFast 310MB | heapAllocated 112MB
13:53:18 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_badargs.js | took 179ms
13:53:18 INFO - checking window state
13:53:18 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_badargs2.js
13:53:18 INFO - Sending InstallTriggered
13:53:18 INFO - JavaScript error: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%7B%7D%7D, line 26: Error: Missing URL property for 'Unsigned XPI'
13:53:18 INFO - MEMORY STAT | vsize 3252MB | residentFast 310MB | heapAllocated 112MB
13:53:18 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_badargs2.js | took 173ms
13:53:18 INFO - checking window state
13:53:18 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_badhash.js
13:53:18 INFO - Sending InstallTriggered
13:53:18 INFO - 1486590798493 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ehr.xpi
13:53:18 INFO - 1486590798495 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:18 INFO - 1486590798495 addons.xpi WARN Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi failed: Downloaded file hash (36ffb0acfd9c6e9682473aaebaab394d38b473c9) did not match provided hash (643b08418599ddbd1ea8a511c90696578fb844b9)
13:53:18 INFO - Sending InstallComplete
13:53:18 INFO - 1486590798508 addons.xpi DEBUG downloadFailed: removing temp file for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:18 INFO - 1486590798508 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ehr.xpi
13:53:18 INFO - MEMORY STAT | vsize 3252MB | residentFast 310MB | heapAllocated 114MB
13:53:18 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_badhash.js | took 249ms
13:53:18 INFO - checking window state
13:53:18 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_badhashtype.js
13:53:18 INFO - Sending InstallTriggered
13:53:18 INFO - 1486590798791 addons.xpi WARN Unknown hash algorithm 'foo' for addon http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi: [Exception... "Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsICryptoHash.initWithString]" nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: onStartRequest :: line 6406" data: no] Stack trace: onStartRequest()@resource://gre/modules/addons/XPIProvider.jsm:6406
13:53:18 INFO - Sending InstallComplete
13:53:18 INFO - 1486590798801 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-jlm.xpi
13:53:18 INFO - MEMORY STAT | vsize 3252MB | residentFast 311MB | heapAllocated 116MB
13:53:18 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_badhashtype.js | took 248ms
13:53:18 INFO - checking window state
13:53:18 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_bug540558.js
13:53:19 INFO - 1486590799060 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vlm.xpi
13:53:19 INFO - 1486590799061 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:19 INFO - 1486590799091 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:19 INFO - 1486590799946 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:20 INFO - 1486590799996 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:20 INFO - 1486590799999 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:20 INFO - 1486590800006 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:20 INFO - 1486590800011 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vlm.xpi
13:53:20 INFO - MEMORY STAT | vsize 3256MB | residentFast 313MB | heapAllocated 119MB
13:53:20 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_bug540558.js | took 1161ms
13:53:20 INFO - checking window state
13:53:20 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_bug611242.js
13:53:20 INFO - Sending PageLoaded
13:53:20 INFO - Sending PageLoaded
13:53:20 INFO - MEMORY STAT | vsize 3268MB | residentFast 325MB | heapAllocated 120MB
13:53:20 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_bug611242.js | took 439ms
13:53:20 INFO - checking window state
13:53:20 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js
13:53:21 INFO - Sending PageLoaded
13:53:21 INFO - Sending PageLoaded
13:53:21 INFO - Sending PageLoaded
13:53:21 INFO - MEMORY STAT | vsize 3269MB | residentFast 325MB | heapAllocated 123MB
13:53:21 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_bug638292.js | took 1330ms
13:53:21 INFO - checking window state
13:53:21 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_bug645699.js
13:53:22 INFO - 1486590802060 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:22 INFO - 1486590802061 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:22 INFO - MEMORY STAT | vsize 3269MB | residentFast 325MB | heapAllocated 123MB
13:53:22 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_bug645699.js | took 232ms
13:53:22 INFO - checking window state
13:53:22 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_cancel.js
13:53:22 INFO - Sending InstallTriggered
13:53:22 INFO - 1486590802343 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-yh6.xpi
13:53:22 INFO - 1486590802344 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:22 INFO - 1486590802526 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:22 INFO - 1486590802539 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned2.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-bfd.xpi
13:53:22 INFO - 1486590802542 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned2.xpi completed.
13:53:22 INFO - 1486590802574 addons.repository DEBUG cacheAddons: enabled false IDs ["signed-xpi@tests.mozilla.org"]
13:53:23 INFO - 1486590803446 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:23 INFO - Sending InstallComplete
13:53:23 INFO - 1486590803447 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-yh6.xpi
13:53:23 INFO - 1486590803448 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned2.xpi
13:53:23 INFO - Sending InstallComplete
13:53:23 INFO - 1486590803449 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned2.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-bfd.xpi
13:53:23 INFO - MEMORY STAT | vsize 3275MB | residentFast 328MB | heapAllocated 110MB
13:53:23 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_cancel.js | took 1388ms
13:53:23 INFO - checking window state
13:53:23 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_concurrent_installs.js
13:53:23 INFO - 1486590803819 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vbj.xpi
13:53:23 INFO - 1486590803820 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:23 INFO - 1486590803833 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:24 INFO - 1486590804730 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:24 INFO - 1486590804823 addons.xpi DEBUG Download started for http://example.org/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-qkc.xpi
13:53:24 INFO - 1486590804824 addons.xpi DEBUG Download of http://example.org/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:24 INFO - 1486590804831 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:24 INFO - 1486590804838 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:24 INFO - 1486590804861 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:24 INFO - Sending InstallComplete
13:53:24 INFO - 1486590804864 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:24 INFO - 1486590804865 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vbj.xpi
13:53:25 INFO - 1486590805713 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.org/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:25 INFO - 1486590805794 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:25 INFO - 1486590805798 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.org/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:25 INFO - Sending InstallComplete
13:53:25 INFO - 1486590805804 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:25 INFO - 1486590805811 addons.xpi DEBUG removeTemporaryFile: http://example.org/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-qkc.xpi
13:53:25 INFO - MEMORY STAT | vsize 3277MB | residentFast 331MB | heapAllocated 115MB
13:53:25 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_concurrent_installs.js | took 2270ms
13:53:25 INFO - checking window state
13:53:25 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_cookies.js
13:53:26 INFO - Sending InstallTriggered
13:53:26 INFO - 1486590806107 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-egj.xpi
13:53:26 INFO - 1486590806109 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:26 INFO - 1486590806109 addons.xpi WARN Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi failed: 500 Internal Server Error
13:53:26 INFO - Sending InstallComplete
13:53:26 INFO - 1486590806119 addons.xpi DEBUG downloadFailed: removing temp file for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:26 INFO - 1486590806119 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-egj.xpi
13:53:26 INFO - MEMORY STAT | vsize 3277MB | residentFast 331MB | heapAllocated 117MB
13:53:26 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_cookies.js | took 265ms
13:53:26 INFO - checking window state
13:53:26 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_cookies2.js
13:53:26 INFO - Sending InstallTriggered
13:53:26 INFO - 1486590806423 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-88c.xpi
13:53:26 INFO - 1486590806425 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:26 INFO - 1486590806460 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:27 INFO - 1486590807397 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:27 INFO - 1486590807492 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:27 INFO - 1486590807497 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:27 INFO - 1486590807505 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:27 INFO - 1486590807511 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-88c.xpi
13:53:27 INFO - Sending InstallComplete
13:53:27 INFO - MEMORY STAT | vsize 3267MB | residentFast 327MB | heapAllocated 103MB
13:53:27 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_cookies2.js | took 1422ms
13:53:27 INFO - checking window state
13:53:27 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_cookies3.js
13:53:27 INFO - Sending InstallTriggered
13:53:27 INFO - 1486590807867 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-wg4.xpi
13:53:27 INFO - 1486590807875 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:27 INFO - 1486590807898 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:28 INFO - 1486590808747 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:28 INFO - 1486590808808 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:28 INFO - 1486590808812 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:28 INFO - Sending InstallComplete
13:53:28 INFO - 1486590808819 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:28 INFO - 1486590808824 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/cookieRedirect.sjs?http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-wg4.xpi
13:53:28 INFO - MEMORY STAT | vsize 3269MB | residentFast 328MB | heapAllocated 107MB
13:53:28 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_cookies3.js | took 1221ms
13:53:28 INFO - checking window state
13:53:28 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_corrupt.js
13:53:29 INFO - Sending InstallTriggered
13:53:29 INFO - 1486590809113 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/corrupt.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-x7c.xpi
13:53:29 INFO - 1486590809116 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/corrupt.xpi completed.
13:53:29 INFO - 1486590809119 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/corrupt.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-x7c.xpi
13:53:29 INFO - 1486590809119 addons.xpi WARN Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/corrupt.xpi failed: [Exception... "Component returned failure code: 0x8052000b (NS_ERROR_FILE_CORRUPTED) [nsIZipReader.open]" nsresult: "0x8052000b (NS_ERROR_FILE_CORRUPTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm :: loadManifest/< :: line 5671" data: no] Stack trace: loadManifest/<()@resource://gre/modules/addons/XPIProvider.jsm:5671 < TaskImpl_run()@resource://gre/modules/Task.jsm:319 < TaskImpl()@resource://gre/modules/Task.jsm:277 < createAsyncFunction/asyncFunction()@resource://gre/modules/Task.jsm:252 < Task_spawn()@resource://gre/modules/Task.jsm:166 < loadManifest()@resource://gre/modules/addons/XPIProvider.jsm:5667 < onStopRequest()@resource://gre/modules/addons/XPIProvider.jsm:6493
13:53:29 INFO - Sending InstallComplete
13:53:29 INFO - 1486590809133 addons.xpi DEBUG downloadFailed: removing temp file for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/corrupt.xpi
13:53:29 INFO - 1486590809133 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/corrupt.xpi does not own temp file
13:53:29 INFO - MEMORY STAT | vsize 3269MB | residentFast 329MB | heapAllocated 108MB
13:53:29 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_corrupt.js | took 273ms
13:53:29 INFO - checking window state
13:53:29 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_datauri.js
13:53:29 INFO - 1486590809431 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:29 INFO - 1486590809431 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:29 INFO - MEMORY STAT | vsize 3269MB | residentFast 329MB | heapAllocated 108MB
13:53:29 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_datauri.js | took 289ms
13:53:29 INFO - checking window state
13:53:29 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_empty.js
13:53:29 INFO - Sending InstallTriggered
13:53:29 INFO - 1486590809765 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/empty.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-73d.xpi
13:53:29 INFO - 1486590809767 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/empty.xpi completed.
13:53:29 INFO - 1486590809772 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/empty.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-73d.xpi
13:53:29 INFO - 1486590809773 addons.xpi WARN Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/empty.xpi failed: Error: File /Users/cltbld/Library/Caches/TemporaryItems/tmp-73d.xpi does not contain a valid install manifest (resource://gre/modules/addons/XPIProvider.jsm:1522:11) JS Stack trace: loadManifestFromZipReader<@XPIProvider.jsm:1522:11 < loadManifest/<@XPIProvider.jsm:5680:28 < TaskImpl_run@Task.jsm:319:42 < TaskImpl@Task.jsm:277:3 < createAsyncFunction/asyncFunction@Task.jsm:252:14 < Task_spawn@Task.jsm:166:12 < loadManifest@XPIProvider.jsm:5667:12 < onStopRequest@XPIProvider.jsm:6493:9
13:53:29 INFO - Sending InstallComplete
13:53:29 INFO - 1486590809783 addons.xpi DEBUG downloadFailed: removing temp file for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/empty.xpi
13:53:29 INFO - 1486590809783 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/empty.xpi does not own temp file
13:53:29 INFO - MEMORY STAT | vsize 3269MB | residentFast 329MB | heapAllocated 111MB
13:53:29 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_empty.js | took 304ms
13:53:29 INFO - checking window state
13:53:29 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_enabled.js
13:53:30 INFO - Sending PageLoaded
13:53:30 INFO - MEMORY STAT | vsize 3269MB | residentFast 329MB | heapAllocated 111MB
13:53:30 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_enabled.js | took 229ms
13:53:30 INFO - checking window state
13:53:30 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_enabled2.js
13:53:30 INFO - Sending PageLoaded
13:53:30 INFO - MEMORY STAT | vsize 3269MB | residentFast 330MB | heapAllocated 112MB
13:53:30 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_enabled2.js | took 217ms
13:53:30 INFO - checking window state
13:53:30 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_enabled3.js
13:53:30 INFO - 1486590810534 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:30 INFO - 1486590810535 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:30 INFO - Sending InstallTriggered
13:53:30 INFO - MEMORY STAT | vsize 3272MB | residentFast 330MB | heapAllocated 113MB
13:53:30 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_enabled3.js | took 219ms
13:53:30 INFO - checking window state
13:53:30 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_hash.js
13:53:30 INFO - Sending InstallTriggered
13:53:30 INFO - 1486590810841 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-7f2.xpi
13:53:30 INFO - 1486590810843 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:30 INFO - 1486590810856 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:31 INFO - 1486590811713 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:31 INFO - 1486590811770 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:31 INFO - 1486590811773 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:31 INFO - Sending InstallComplete
13:53:31 INFO - 1486590811780 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:31 INFO - 1486590811786 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-7f2.xpi
13:53:31 INFO - MEMORY STAT | vsize 3272MB | residentFast 331MB | heapAllocated 115MB
13:53:31 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_hash.js | took 1181ms
13:53:31 INFO - checking window state
13:53:31 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_hash2.js
13:53:32 INFO - Sending InstallTriggered
13:53:32 INFO - 1486590812057 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ioa.xpi
13:53:32 INFO - 1486590812059 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:32 INFO - 1486590812072 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:32 INFO - 1486590812930 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:32 INFO - 1486590812971 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:32 INFO - 1486590812974 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:32 INFO - Sending InstallComplete
13:53:32 INFO - 1486590812980 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:32 INFO - 1486590812984 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ioa.xpi
13:53:33 INFO - MEMORY STAT | vsize 3273MB | residentFast 332MB | heapAllocated 117MB
13:53:33 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_hash2.js | took 1153ms
13:53:33 INFO - checking window state
13:53:33 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_httphash.js
13:53:33 INFO - Sending InstallTriggered
13:53:33 INFO - 1486590813281 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-tcm.xpi
13:53:33 INFO - 1486590813283 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:33 INFO - 1486590813304 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:34 INFO - 1486590814164 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:34 INFO - 1486590814266 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:34 INFO - 1486590814270 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:34 INFO - Sending InstallComplete
13:53:34 INFO - 1486590814277 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:34 INFO - 1486590814282 addons.xpi DEBUG removeTemporaryFile: https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-tcm.xpi
13:53:34 INFO - MEMORY STAT | vsize 3278MB | residentFast 333MB | heapAllocated 117MB
13:53:34 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_httphash.js | took 1328ms
13:53:34 INFO - checking window state
13:53:34 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_httphash2.js
13:53:34 INFO - Sending InstallTriggered
13:53:34 INFO - 1486590814699 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-gzr.xpi
13:53:34 INFO - 1486590814700 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:34 INFO - 1486590814701 addons.xpi WARN Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi failed: Downloaded file hash (36ffb0acfd9c6e9682473aaebaab394d38b473c9) did not match provided hash (foobar)
13:53:34 INFO - Sending InstallComplete
13:53:34 INFO - 1486590814713 addons.xpi DEBUG downloadFailed: removing temp file for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:34 INFO - 1486590814713 addons.xpi DEBUG removeTemporaryFile: https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-gzr.xpi
13:53:34 INFO - MEMORY STAT | vsize 3270MB | residentFast 330MB | heapAllocated 112MB
13:53:34 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_httphash2.js | took 312ms
13:53:34 INFO - checking window state
13:53:34 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_httphash3.js
13:53:34 INFO - Sending InstallTriggered
13:53:34 INFO - 1486590814991 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-1co.xpi
13:53:34 INFO - 1486590814993 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:35 INFO - 1486590815013 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:35 INFO - 1486590815880 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:35 INFO - 1486590815945 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:35 INFO - 1486590815950 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:35 INFO - Sending InstallComplete
13:53:35 INFO - 1486590815955 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:35 INFO - 1486590815960 addons.xpi DEBUG removeTemporaryFile: https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-1co.xpi
13:53:36 INFO - MEMORY STAT | vsize 3270MB | residentFast 331MB | heapAllocated 116MB
13:53:36 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_httphash3.js | took 1211ms
13:53:36 INFO - checking window state
13:53:36 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_httphash4.js
13:53:36 INFO - Sending InstallTriggered
13:53:36 INFO - 1486590816251 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-m9j.xpi
13:53:36 INFO - 1486590816253 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:36 INFO - 1486590816273 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:37 INFO - 1486590817130 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:37 INFO - 1486590817175 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:37 INFO - 1486590817178 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:37 INFO - Sending InstallComplete
13:53:37 INFO - 1486590817182 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:37 INFO - 1486590817188 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-m9j.xpi
13:53:37 INFO - MEMORY STAT | vsize 3271MB | residentFast 332MB | heapAllocated 119MB
13:53:37 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_httphash4.js | took 1200ms
13:53:37 INFO - checking window state
13:53:37 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_httphash5.js
13:53:37 INFO - Sending InstallTriggered
13:53:37 INFO - 1486590817499 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-h6p.xpi
13:53:37 INFO - 1486590817501 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:37 INFO - 1486590817516 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:38 INFO - 1486590818380 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:38 INFO - 1486590818422 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:38 INFO - 1486590818425 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:38 INFO - Sending InstallComplete
13:53:38 INFO - 1486590818430 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:38 INFO - 1486590818435 addons.xpi DEBUG removeTemporaryFile: https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:36ffb0acfd9c6e9682473aaebaab394d38b473c9|https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/hashRedirect.sjs?sha1:foobar|http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-h6p.xpi
13:53:38 INFO - MEMORY STAT | vsize 3271MB | residentFast 333MB | heapAllocated 118MB
13:53:38 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_httphash5.js | took 1183ms
13:53:38 INFO - checking window state
13:53:38 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_httphash6.js
13:53:38 INFO - Sending InstallTriggered
13:53:38 INFO - 1486590818710 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-psq.xpi
13:53:38 INFO - 1486590818711 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect completed.
13:53:38 INFO - 1486590818712 addons.xpi WARN Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect failed: Downloaded file hash (36ffb0acfd9c6e9682473aaebaab394d38b473c9) did not match provided hash (foo)
13:53:38 INFO - Sending InstallComplete
13:53:38 INFO - 1486590818724 addons.xpi DEBUG downloadFailed: removing temp file for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect
13:53:38 INFO - 1486590818724 addons.xpi DEBUG removeTemporaryFile: https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-psq.xpi
13:53:38 INFO - 1486590818773 addons.xpi DEBUG removeTemporaryFile: https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect does not own temp file
13:53:38 INFO - 1486590818827 addons.xpi DEBUG Download started for https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ybe.xpi
13:53:38 INFO - 1486590818829 addons.xpi DEBUG Download of https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect completed.
13:53:38 INFO - 1486590818843 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:39 INFO - 1486590819714 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect
13:53:39 INFO - 1486590819781 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:39 INFO - 1486590819784 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect ready; waiting for restart.
13:53:39 INFO - 1486590819789 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:39 INFO - 1486590819794 addons.xpi DEBUG removeTemporaryFile: https://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/redirect.sjs?mode=redirect removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-ybe.xpi
13:53:39 INFO - MEMORY STAT | vsize 3275MB | residentFast 334MB | heapAllocated 119MB
13:53:39 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_httphash6.js | took 1347ms
13:53:39 INFO - checking window state
13:53:39 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_installchrome.js
13:53:40 INFO - 1486590820076 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-dvi.xpi
13:53:40 INFO - 1486590820078 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:40 INFO - 1486590820095 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:40 INFO - 1486590820964 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:41 INFO - 1486590821022 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:41 INFO - 1486590821025 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:41 INFO - 1486590821030 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:41 INFO - 1486590821034 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-dvi.xpi
13:53:41 INFO - MEMORY STAT | vsize 3285MB | residentFast 344MB | heapAllocated 124MB
13:53:41 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_installchrome.js | took 1193ms
13:53:41 INFO - checking window state
13:53:41 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_localfile.js
13:53:41 INFO - 1486590821297 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:42 INFO - 1486590822198 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from file:///builds/slave/test/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:42 INFO - 1486590822285 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:42 INFO - 1486590822291 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from file:///builds/slave/test/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:42 INFO - 1486590822297 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:42 INFO - 1486590822307 addons.xpi DEBUG removeTemporaryFile: file:///builds/slave/test/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:42 INFO - MEMORY STAT | vsize 3285MB | residentFast 345MB | heapAllocated 107MB
13:53:42 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_localfile.js | took 1321ms
13:53:42 INFO - checking window state
13:53:42 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_localfile2.js
13:53:42 INFO - Sending InstallTriggered
13:53:42 INFO - JavaScript error: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/installtrigger.html?%7B%22Unsigned%20XPI%22%3A%22file%3A%2F%2F%2Fbuilds%2Fslave%2Ftest%2Fbuild%2Ftests%2Fmochitest%2Fbrowser%2Ftoolkit%2Fmozapps%2Fextensions%2Ftest%2Fxpinstall%2Famosigned.xpi%22%7D, line 26: Error: Insufficient permissions to install: file:///builds/slave/test/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:42 INFO - MEMORY STAT | vsize 3282MB | residentFast 341MB | heapAllocated 105MB
13:53:42 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_localfile2.js | took 444ms
13:53:42 INFO - checking window state
13:53:42 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_localfile3.js
13:53:43 INFO - 1486590823078 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:43 INFO - 1486590823085 addons.xpi DEBUG Cancelling download of file:///builds/slave/test/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:43 INFO - 1486590823088 addons.xpi DEBUG removeTemporaryFile: file:///builds/slave/test/build/tests/mochitest/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:43 INFO - MEMORY STAT | vsize 3283MB | residentFast 343MB | heapAllocated 108MB
13:53:43 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_localfile3.js | took 209ms
13:53:43 INFO - checking window state
13:53:43 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_localfile4.js
13:53:43 INFO - 1486590823283 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:43 INFO - 1486590823284 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:43 INFO - Sending InstallTriggered
13:53:43 INFO - MEMORY STAT | vsize 3283MB | residentFast 344MB | heapAllocated 110MB
13:53:43 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_localfile4.js | took 194ms
13:53:43 INFO - checking window state
13:53:43 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_multipackage.js
13:53:43 INFO - 1486590823557 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-538.xpi
13:53:43 INFO - 1486590823560 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi completed.
13:53:43 INFO - 1486590823568 addons.xpi WARN Add-on null is not compatible with application version.
13:53:43 INFO - 1486590823570 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-538.xpi
13:53:43 INFO - 1486590823587 addons.repository DEBUG cacheAddons: enabled false IDs ["signed-xpi@tests.mozilla.org"]
13:53:43 INFO - 1486590823590 addons.xpi DEBUG Base multi-package XPI install came from amosigned2.xpi
13:53:43 INFO - 1486590823590 addons.xpi DEBUG Creating linked install from amosigned.xpi
13:53:43 INFO - 1486590823600 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:44 INFO - 1486590824464 addons.xpi DEBUG Starting install of signed-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi
13:53:44 INFO - 1486590824466 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi
13:53:44 INFO - 1486590824531 addons.xpi DEBUG Addon signed-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:44 INFO - 1486590824535 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:44 INFO - 1486590824540 addons.xpi DEBUG Staged install of signed-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi ready; waiting for restart.
13:53:44 INFO - 1486590824542 addons.xpi DEBUG Cancelling install of signed-xpi@tests.mozilla.org
13:53:44 INFO - 1486590824544 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-mqu.xpi
13:53:44 INFO - 1486590824547 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi ready; waiting for restart.
13:53:44 INFO - 1486590824552 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:44 INFO - 1486590824557 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/multipackage.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-pot.xpi
13:53:44 INFO - MEMORY STAT | vsize 3287MB | residentFast 346MB | heapAllocated 113MB
13:53:44 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_multipackage.js | took 1203ms
13:53:44 INFO - checking window state
13:53:44 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_navigateaway.js
13:53:44 INFO - Sending InstallTriggered
13:53:44 INFO - 1486590824829 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-7vv.xpi
13:53:44 INFO - 1486590824833 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:44 INFO - 1486590824850 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:45 INFO - Sending PageLoaded
13:53:45 INFO - 1486590825747 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:45 INFO - 1486590825863 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:45 INFO - 1486590825868 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:45 INFO - Sending InstallComplete
13:53:45 INFO - 1486590825876 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:45 INFO - 1486590825880 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-7vv.xpi
13:53:45 INFO - MEMORY STAT | vsize 3288MB | residentFast 346MB | heapAllocated 113MB
13:53:45 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_navigateaway.js | took 1296ms
13:53:46 INFO - checking window state
13:53:46 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_navigateaway2.js
13:53:46 INFO - Sending InstallTriggered
13:53:46 INFO - 1486590826128 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vzg.xpi
13:53:46 INFO - 1486590826152 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:46 INFO - 1486590826152 addons.xpi DEBUG Cancelled download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:46 INFO - 1486590826158 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vzg.xpi
13:53:46 INFO - MEMORY STAT | vsize 3288MB | residentFast 347MB | heapAllocated 114MB
13:53:46 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_navigateaway2.js | took 204ms
13:53:46 INFO - checking window state
13:53:46 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_navigateaway4.js
13:53:46 INFO - Sending InstallTriggered
13:53:46 INFO - 1486590826425 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-bif.xpi
13:53:46 INFO - 1486590826426 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:46 INFO - 1486590826440 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:46 INFO - 1486590826982 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:46 INFO - 1486590826984 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-bif.xpi
13:53:46 INFO - Sending PageLoaded
13:53:46 INFO - Sending InstallComplete
13:53:47 INFO - MEMORY STAT | vsize 3288MB | residentFast 347MB | heapAllocated 118MB
13:53:47 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_navigateaway4.js | took 1265ms
13:53:47 INFO - checking window state
13:53:47 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_offline.js
13:53:47 INFO - Sending InstallTriggered
13:53:47 INFO - 1486590827668 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-v5e.xpi
13:53:47 INFO - 1486590827669 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:47 INFO - 1486590827676 addons.xpi DEBUG Cancelled download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:47 INFO - Sending InstallComplete
13:53:47 INFO - 1486590827681 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-v5e.xpi
13:53:47 INFO - MEMORY STAT | vsize 3289MB | residentFast 348MB | heapAllocated 119MB
13:53:47 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_offline.js | took 282ms
13:53:47 INFO - checking window state
13:53:47 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_relative.js
13:53:48 INFO - Sending InstallTriggered
13:53:48 INFO - 1486590828046 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-t3o.xpi
13:53:48 INFO - 1486590828048 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:48 INFO - 1486590828065 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:48 INFO - 1486590828930 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:48 INFO - 1486590828973 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:48 INFO - 1486590828976 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:48 INFO - Sending InstallComplete
13:53:48 INFO - 1486590828984 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:48 INFO - 1486590828989 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-t3o.xpi
13:53:49 INFO - MEMORY STAT | vsize 3287MB | residentFast 349MB | heapAllocated 119MB
13:53:49 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_relative.js | took 1217ms
13:53:49 INFO - checking window state
13:53:49 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_softwareupdate.js
13:53:49 INFO - 1486590829262 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-fio.xpi
13:53:49 INFO - 1486590829264 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:49 INFO - 1486590829281 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:50 INFO - 1486590830147 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:50 INFO - 1486590830228 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:50 INFO - 1486590830231 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:50 INFO - 1486590830238 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:50 INFO - 1486590830243 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-fio.xpi
13:53:50 INFO - MEMORY STAT | vsize 3292MB | residentFast 350MB | heapAllocated 120MB
13:53:50 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_softwareupdate.js | took 1259ms
13:53:50 INFO - checking window state
13:53:50 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_switchtab.js
13:53:50 INFO - Sending InstallTriggered
13:53:50 INFO - 1486590830582 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vum.xpi
13:53:50 INFO - 1486590830583 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:50 INFO - 1486590830600 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:51 INFO - 1486590831480 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:51 INFO - 1486590831549 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:51 INFO - 1486590831551 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:51 INFO - Sending InstallComplete
13:53:51 INFO - 1486590831560 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:51 INFO - 1486590831565 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-vum.xpi
13:53:51 INFO - MEMORY STAT | vsize 3284MB | residentFast 346MB | heapAllocated 113MB
13:53:51 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_switchtab.js | took 1206ms
13:53:51 INFO - checking window state
13:53:51 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_trigger_redirect.js
13:53:51 INFO - 1486590831899 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-gex.xpi
13:53:51 INFO - 1486590831902 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:51 INFO - 1486590831929 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:52 INFO - 1486590832797 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:52 INFO - 1486590832841 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:52 INFO - 1486590832844 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:52 INFO - Sending InstallComplete
13:53:52 INFO - 1486590832849 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:52 INFO - 1486590832854 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-gex.xpi
13:53:52 INFO - MEMORY STAT | vsize 3285MB | residentFast 347MB | heapAllocated 115MB
13:53:52 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_trigger_redirect.js | took 1263ms
13:53:52 INFO - checking window state
13:53:52 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_unsigned_trigger_xorigin.js
13:53:53 INFO - 1486590833181 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:53 INFO - 1486590833182 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:53 INFO - Sending InstallTriggered
13:53:53 INFO - Sending InstallComplete
13:53:53 INFO - MEMORY STAT | vsize 3285MB | residentFast 347MB | heapAllocated 118MB
13:53:53 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_unsigned_trigger_xorigin.js | took 296ms
13:53:53 INFO - checking window state
13:53:53 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist.js
13:53:53 INFO - Sending InstallTriggered
13:53:53 INFO - 1486590833511 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-t5p.xpi
13:53:53 INFO - 1486590833513 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:53 INFO - 1486590833538 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:54 INFO - 1486590834398 addons.xpi DEBUG Starting install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:54 INFO - 1486590834455 addons.xpi DEBUG Addon unsigned-xpi@tests.mozilla.org will be installed as a packed xpi
13:53:54 INFO - 1486590834459 addons.xpi DEBUG Staged install of unsigned-xpi@tests.mozilla.org from http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi ready; waiting for restart.
13:53:54 INFO - 1486590834466 addons.xpi DEBUG Cancelling install of unsigned-xpi@tests.mozilla.org
13:53:54 INFO - 1486590834472 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-t5p.xpi
13:53:54 INFO - MEMORY STAT | vsize 3284MB | residentFast 347MB | heapAllocated 116MB
13:53:54 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist.js | took 1231ms
13:53:54 INFO - checking window state
13:53:54 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist2.js
13:53:54 INFO - 1486590834687 addons.xpi DEBUG Cancelling download of http://example.org/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:54 INFO - 1486590834689 addons.xpi DEBUG removeTemporaryFile: http://example.org/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:54 INFO - Sending InstallTriggered
13:53:54 INFO - MEMORY STAT | vsize 3284MB | residentFast 347MB | heapAllocated 117MB
13:53:54 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist2.js | took 216ms
13:53:54 INFO - checking window state
13:53:54 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist3.js
13:53:54 INFO - 1486590834983 addons.xpi DEBUG Download started for http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi to file /Users/cltbld/Library/Caches/TemporaryItems/tmp-t03.xpi
13:53:54 INFO - 1486590834985 addons.xpi DEBUG Download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi completed.
13:53:55 INFO - 1486590835003 addons.repository DEBUG cacheAddons: enabled false IDs ["unsigned-xpi@tests.mozilla.org"]
13:53:55 INFO - 1486590835847 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:55 INFO - 1486590835848 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi removing temp file /Users/cltbld/Library/Caches/TemporaryItems/tmp-t03.xpi
13:53:55 INFO - MEMORY STAT | vsize 3288MB | residentFast 348MB | heapAllocated 118MB
13:53:55 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist3.js | took 1109ms
13:53:55 INFO - checking window state
13:53:55 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist4.js
13:53:56 INFO - 1486590836089 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:56 INFO - 1486590836091 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:56 INFO - MEMORY STAT | vsize 3288MB | residentFast 348MB | heapAllocated 119MB
13:53:56 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist4.js | took 226ms
13:53:56 INFO - checking window state
13:53:56 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist5.js
13:53:56 INFO - 1486590836320 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:56 INFO - 1486590836322 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:56 INFO - MEMORY STAT | vsize 3288MB | residentFast 348MB | heapAllocated 119MB
13:53:56 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist5.js | took 198ms
13:53:56 INFO - checking window state
13:53:56 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist6.js
13:53:56 INFO - 1486590836543 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:56 INFO - 1486590836543 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:56 INFO - MEMORY STAT | vsize 3288MB | residentFast 348MB | heapAllocated 121MB
13:53:56 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist6.js | took 199ms
13:53:56 INFO - checking window state
13:53:56 INFO - TEST-START | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist7.js
13:53:56 INFO - 1486590836770 addons.xpi DEBUG Cancelling download of http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi
13:53:56 INFO - 1486590836771 addons.xpi DEBUG removeTemporaryFile: http://example.com/browser/toolkit/mozapps/extensions/test/xpinstall/amosigned.xpi does not own temp file
13:53:56 INFO - MEMORY STAT | vsize 3288MB | residentFast 349MB | heapAllocated 123MB
13:53:56 INFO - TEST-OK | toolkit/mozapps/extensions/test/xpinstall/browser_whitelist7.js | took 197ms
13:53:56 INFO - checking window state
13:53:57 INFO - Completed ShutdownLeaks collections in process 1972
13:53:59 INFO - Completed ShutdownLeaks collections in process 1971
13:53:59 INFO - TEST-START | Shutdown
13:53:59 INFO - Browser Chrome Test Summary
13:53:59 INFO - Passed: 425
13:53:59 INFO - Failed: 0
13:53:59 INFO - Todo: 0
13:53:59 INFO - Mode: e10s
13:53:59 INFO - *** End BrowserChrome Test Results ***
13:54:00 INFO - -*- PresentationControlService.js: PresentationControlService - close
13:54:00 INFO - TEST-INFO | Main app process: exit 0
13:54:00 INFO - runtests.py | Application ran for: 0:00:57.188114
13:54:00 INFO - zombiecheck | Reading PID log: /var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/tmp7u5LUBpidlog
13:54:00 INFO - ==> process 1971 launched child process 1972
13:54:00 INFO - zombiecheck | Checking for orphan process with PID: 1972
13:54:00 INFO - Stopping web server
13:54:00 INFO - Stopping web socket server
13:54:00 INFO - Stopping ssltunnel
13:54:00 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
13:54:00 INFO - runtests.py | Running tests: end.
13:54:00 INFO - Buffered messages finished
13:54:00 INFO - TEST-INFO | checking window state
13:54:00 INFO - Browser Chrome Test Summary
13:54:00 INFO - Passed: 7499
13:54:00 INFO - Failed: 2
13:54:00 INFO - Todo: 3
13:54:00 INFO - Mode: e10s
13:54:00 INFO - *** End BrowserChrome Test Results ***
13:54:00 INFO - Buffered messages finished
13:54:00 INFO - SUITE-END | took 619s
13:54:00 INFO - Return code: 0
13:54:00 INFO - TinderboxPrint: mochitest-browser-chrome-chunked 377/2/3
13:54:00 WARNING - # TBPL WARNING #
13:54:00 WARNING - setting return code to 1
13:54:00 WARNING - The mochitest suite: browser-chrome-chunked ran with return status: WARNING
13:54:00 INFO - Running post-action listener: _package_coverage_data
13:54:00 INFO - Running post-action listener: _resource_record_post_action
13:54:00 INFO - [mozharness: 2017-02-08 21:54:00.782552Z] Finished run-tests step (success)
13:54:00 INFO - Running post-run listener: _resource_record_post_run
13:54:00 WARNING - error reading instance_metadata: Traceback (most recent call last):
13:54:00 WARNING - File "/builds/slave/test/scripts/mozharness/base/python.py", line 519, in perfherder_resource_options
13:54:00 WARNING - instance = im['aws_instance_type'].encode('ascii')
13:54:00 WARNING - KeyError: 'aws_instance_type'
13:54:00 INFO - Validating Perfherder data against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
13:54:00 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 56.935903426791285}, {"name": "io_write_bytes", "value": 1123628544}, {"name": "io.read_bytes", "value": 76577792}, {"name": "io_write_time", "value": 5808}, {"name": "io_read_time", "value": 900}], "extraOptions": ["e10s", "buildbot-unknown"], "name": "mochitest.browser-chrome-chunked.6.overall"}, {"subtests": [{"name": "time", "value": 21.51939296722412}, {"name": "cpu_percent", "value": 54.092499999999994}], "name": "mochitest.browser-chrome-chunked.6.install"}, {"subtests": [{"name": "time", "value": 0.0003509521484375}], "name": "mochitest.browser-chrome-chunked.6.stage-files"}, {"subtests": [{"name": "time", "value": 621.392007112503}, {"name": "cpu_percent", "value": 57.037701612903234}], "name": "mochitest.browser-chrome-chunked.6.run-tests"}]}
13:54:00 INFO - Total resource usage - Wall time: 642s; CPU: 57.0%; Read bytes: 76577792; Write bytes: 1123628544; Read time: 900; Write time: 5808
13:54:00 INFO - TinderboxPrint: CPU usage 57.0%
13:54:00 INFO - TinderboxPrint: I/O read bytes / time 76,577,792 / 900
13:54:00 INFO - TinderboxPrint: I/O write bytes / time 1,123,628,544 / 5,808
13:54:00 INFO - TinderboxPrint: CPU idle 1,106.4 (43.1%)
13:54:00 INFO - TinderboxPrint: CPU system 606.0 (23.6%)
13:54:00 INFO - TinderboxPrint: CPU user 856.7 (33.3%)
13:54:00 INFO - TinderboxPrint: Swap in / out 257,130,496 / 0
13:54:00 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 186853888; Write bytes: 191490048; Read time: 16805; Write time: 398
13:54:00 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
13:54:00 INFO - run-tests - Wall time: 621s; CPU: 57.0%; Read bytes: 62467072; Write bytes: 909786624; Read time: 833; Write time: 5331
13:54:01 INFO - Running post-run listener: _upload_blobber_files
13:54:01 INFO - Blob upload gear active.
13:54:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
13:54:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
13:54:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'mozilla-beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
13:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b mozilla-beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
13:54:01 INFO - (blobuploader) - INFO - Open directory for files ...
13:54:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/browser-chrome-chunked_errorsummary.log ...
13:54:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:54:01 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:54:02 INFO - (blobuploader) - INFO - TinderboxPrint: browser-chrome-chunked_errorsummary.log: uploaded
13:54:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:54:02 INFO - (blobuploader) - INFO - Done attempting.
13:54:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/browser-chrome-chunked_raw.log ...
13:54:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:54:02 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:54:03 INFO - (blobuploader) - INFO - TinderboxPrint: browser-chrome-chunked_raw.log: uploaded
13:54:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:54:03 INFO - (blobuploader) - INFO - Done attempting.
13:54:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/mozilla-test-fail-screenshot_ClSaEV.png ...
13:54:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:54:03 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:54:04 INFO - (blobuploader) - INFO - TinderboxPrint: mozilla-test-fail-screenshot_ClSaEV.png: uploaded
13:54:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:54:04 INFO - (blobuploader) - INFO - Done attempting.
13:54:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
13:54:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:54:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:54:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
13:54:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:54:06 INFO - (blobuploader) - INFO - Done attempting.
13:54:06 INFO - (blobuploader) - INFO - Iteration through files over.
13:54:06 INFO - Return code: 0
13:54:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
13:54:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
13:54:06 INFO - Setting buildbot property blobber_files to {"resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/eba062c0da70bd2f89752aa3adb3118b715dc0c983f89c76e0a23dabfe870e5d42674f81a6bca66a5a5699ec95b949e3b5eb7c113fed8bd695c84b3cb0ecaad5", "mozilla-test-fail-screenshot_ClSaEV.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/62e1f6bff620235bd3b7d8719d101ce5e34bab9951708a14842ab6db2e290f6768e3e43dff577bf6aa34b9b0ca6bee107f1c43327500ca432a685c6b4003e338", "browser-chrome-chunked_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/9ef65082cf586c3f0dafd6f70dfa9a05ef0de2a8088725d7c4804054ddc72eb81b039dd3d9dd6fa7f2eaa7645bf8ee8186a9ce3397c9fe6f2783d731e3431396", "browser-chrome-chunked_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/3f4ebf75fe2fcb47cab475ef7b912562164b562d77b008544455f41cc3937d2df9e97030d48908d5a989cf10bf368963bafc146c3c2b0e043c01885382e8ddb4"}
13:54:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
13:54:06 INFO - Writing to file /builds/slave/test/properties/blobber_files
13:54:06 INFO - Contents:
13:54:06 INFO - blobber_files:{"resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/eba062c0da70bd2f89752aa3adb3118b715dc0c983f89c76e0a23dabfe870e5d42674f81a6bca66a5a5699ec95b949e3b5eb7c113fed8bd695c84b3cb0ecaad5", "mozilla-test-fail-screenshot_ClSaEV.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/62e1f6bff620235bd3b7d8719d101ce5e34bab9951708a14842ab6db2e290f6768e3e43dff577bf6aa34b9b0ca6bee107f1c43327500ca432a685c6b4003e338", "browser-chrome-chunked_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/9ef65082cf586c3f0dafd6f70dfa9a05ef0de2a8088725d7c4804054ddc72eb81b039dd3d9dd6fa7f2eaa7645bf8ee8186a9ce3397c9fe6f2783d731e3431396", "browser-chrome-chunked_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/3f4ebf75fe2fcb47cab475ef7b912562164b562d77b008544455f41cc3937d2df9e97030d48908d5a989cf10bf368963bafc146c3c2b0e043c01885382e8ddb4"}
13:54:06 INFO - Running post-run listener: copy_logs_to_upload_dir
13:54:06 INFO - Copying logs to upload dir...
13:54:06 INFO - mkdir: /builds/slave/test/build/upload/logs
13:54:06 INFO - Copying logs to upload dir...
13:54:06 WARNING - returning nonzero exit status 1
program finished with exit code 1
elapsedTime=733.899587
========= master_lag: 0.07 =========
========= Finished '/tools/buildbot/bin/python -u ...' warnings (results: 1, elapsed: 12 mins, 13 secs) (at 2017-02-08 13:54:06.920149) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 13:54:06.923303) =========
bash -c 'for file in `ls -1`; do cat $file; done'
in dir /builds/slave/test/properties (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RwWn3Gzh7m/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PWD=/builds/slave/test/properties
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6GJWB4taOn/Listeners
TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
blobber_files:{"resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/eba062c0da70bd2f89752aa3adb3118b715dc0c983f89c76e0a23dabfe870e5d42674f81a6bca66a5a5699ec95b949e3b5eb7c113fed8bd695c84b3cb0ecaad5", "mozilla-test-fail-screenshot_ClSaEV.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/62e1f6bff620235bd3b7d8719d101ce5e34bab9951708a14842ab6db2e290f6768e3e43dff577bf6aa34b9b0ca6bee107f1c43327500ca432a685c6b4003e338", "browser-chrome-chunked_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/9ef65082cf586c3f0dafd6f70dfa9a05ef0de2a8088725d7c4804054ddc72eb81b039dd3d9dd6fa7f2eaa7645bf8ee8186a9ce3397c9fe6f2783d731e3431396", "browser-chrome-chunked_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/3f4ebf75fe2fcb47cab475ef7b912562164b562d77b008544455f41cc3937d2df9e97030d48908d5a989cf10bf368963bafc146c3c2b0e043c01885382e8ddb4"}
build_url:https://queue.taskcluster.net/v1/task/PuswHjImTeyijjT6IpwCiQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg
program finished with exit code 0
elapsedTime=0.011381
build_url: 'https://queue.taskcluster.net/v1/task/PuswHjImTeyijjT6IpwCiQ/artifacts/public/build/firefox-52.0.en-US.mac.dmg'
blobber_files: '{"resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/eba062c0da70bd2f89752aa3adb3118b715dc0c983f89c76e0a23dabfe870e5d42674f81a6bca66a5a5699ec95b949e3b5eb7c113fed8bd695c84b3cb0ecaad5", "mozilla-test-fail-screenshot_ClSaEV.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/62e1f6bff620235bd3b7d8719d101ce5e34bab9951708a14842ab6db2e290f6768e3e43dff577bf6aa34b9b0ca6bee107f1c43327500ca432a685c6b4003e338", "browser-chrome-chunked_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/9ef65082cf586c3f0dafd6f70dfa9a05ef0de2a8088725d7c4804054ddc72eb81b039dd3d9dd6fa7f2eaa7645bf8ee8186a9ce3397c9fe6f2783d731e3431396", "browser-chrome-chunked_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-beta/sha512/3f4ebf75fe2fcb47cab475ef7b912562164b562d77b008544455f41cc3937d2df9e97030d48908d5a989cf10bf368963bafc146c3c2b0e043c01885382e8ddb4"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-08 13:54:06.958477) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 13:54:06.958882) =========
rm -f oauth.txt
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RwWn3Gzh7m/Render
GIT_SHARE_BASE_DIR=/builds/git-shared
HG_SHARE_BASE_DIR=/builds/hg-shared
HOME=/Users/cltbld
IDLEIZER_DISABLE_SHUTDOWN=true
LOGNAME=cltbld
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11
PWD=/builds/slave/test
RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg
SHELL=/bin/bash
SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.6GJWB4taOn/Listeners
TMPDIR=/var/folders/5z/1cbw0knd0ls912sq61_r0_0400000w/T/
TWISTD_LOG_PATH=/builds/slave/twistd.log
USER=cltbld
VERSIONER_PYTHON_PREFER_32_BIT=no
VERSIONER_PYTHON_VERSION=2.7
XPC_FLAGS=0x0
XPC_SERVICE_NAME=0
__CF_USER_TEXT_ENCODING=0x1C:0:0
using PTY: False
program finished with exit code 0
elapsedTime=0.005209
========= master_lag: 0.04 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-08 13:54:07.007730) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 13:54:07.008076) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-08 13:54:07.008389) =========
========= Total master_lag: 0.47 =========