builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0010 starttime: 1497644018.65 results: success (0) buildid: 20170616111822 builduid: ece30b210a834613b688eb410673226c revision: b08a305464d127e25fab19f358fac15fceb52954 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.652045) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.652459) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.652728) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.677032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.677290) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ye2DmR6h3p/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat 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.An2TUFSl8Y/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-16 13:13:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-06-16 13:13:38 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134840 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.832468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.832964) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ye2DmR6h3p/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat 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.An2TUFSl8Y/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn 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.040111 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:38.895140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-16 13:13:38.895853) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b08a305464d127e25fab19f358fac15fceb52954 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b08a305464d127e25fab19f358fac15fceb52954 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ye2DmR6h3p/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat 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.An2TUFSl8Y/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-16 13:13:38,992 truncating revision to first 12 chars 2017-06-16 13:13:38,992 Setting DEBUG logging. 2017-06-16 13:13:38,992 attempt 1/10 2017-06-16 13:13:38,992 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b08a305464d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-16 13:13:40,532 unpacking tar archive at: mozilla-beta-b08a305464d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.816002 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-16 13:13:40.735919) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:40.736353) ========= script_repo_revision: b08a305464d127e25fab19f358fac15fceb52954 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:40.736758) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:40.737047) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-16 13:13:40.754955) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-16 13:13:40.755348) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ye2DmR6h3p/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json 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.An2TUFSl8Y/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:13:40 INFO - MultiFileLogger online at 20170616 13:13:40 in /builds/slave/test 13:13:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:13:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:13:40 INFO - {'append_to_log': False, 13:13:40 INFO - 'base_work_dir': '/builds/slave/test', 13:13:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:13:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:13:40 INFO - 'branch': 'Mozilla-Beta', 13:13:40 INFO - 'buildbot_json_path': 'buildprops.json', 13:13:40 INFO - 'config_files': ('talos/mac_config.py',), 13:13:40 INFO - 'default_actions': ('clobber', 13:13:40 INFO - 'read-buildbot-config', 13:13:40 INFO - 'download-and-extract', 13:13:40 INFO - 'populate-webroot', 13:13:40 INFO - 'create-virtualenv', 13:13:40 INFO - 'install', 13:13:40 INFO - 'setup-mitmproxy', 13:13:40 INFO - 'run-tests'), 13:13:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:13:40 INFO - 'download_minidump_stackwalk': True, 13:13:40 INFO - 'download_symbols': 'ondemand', 13:13:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:13:40 INFO - 'tooltool.py': '/tools/tooltool.py', 13:13:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:13:40 INFO - '/tools/misc-python/virtualenv.py')}, 13:13:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:13:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:13:40 INFO - 'gecko_profile': False, 13:13:40 INFO - 'gecko_profile_interval': 0, 13:13:40 INFO - 'installer_path': 'installer.exe', 13:13:40 INFO - 'log_level': 'info', 13:13:40 INFO - 'log_name': 'talos', 13:13:40 INFO - 'log_to_console': True, 13:13:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:13:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:13:40 INFO - 'opt_config_files': (), 13:13:40 INFO - 'pip_index': False, 13:13:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:40 INFO - 'cmd': ('bash', 13:13:40 INFO - '-c', 13:13:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:40 INFO - 'enabled': True, 13:13:40 INFO - 'halt_on_failure': False, 13:13:40 INFO - 'name': 'check_screen_resolution'},), 13:13:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:13:40 INFO - 'cmd': ('bash', 13:13:40 INFO - '-c', 13:13:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:13:40 INFO - 'enabled': True, 13:13:40 INFO - 'halt_on_failure': False, 13:13:40 INFO - 'name': 'check_screen_resolution'},), 13:13:40 INFO - 'run_cmd_checks_enabled': True, 13:13:40 INFO - 'suite': 'dromaeojs-e10s', 13:13:40 INFO - 'system_bits': '32', 13:13:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:13:40 INFO - 'title': 't-yosemite-r7-0010', 13:13:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:13:40 INFO - 'use_talos_json': True, 13:13:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:13:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:13:40 INFO - 'work_dir': 'build'} 13:13:40 INFO - [mozharness: 2017-06-16 20:13:40.965288Z] Running clobber step. 13:13:40 INFO - Running pre-action listener: _resource_record_pre_action 13:13:40 INFO - Running main action method: clobber 13:13:40 INFO - rmtree: /builds/slave/test/build 13:13:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:13:43 INFO - Running post-action listener: _resource_record_post_action 13:13:43 INFO - [mozharness: 2017-06-16 20:13:43.379201Z] Finished clobber step (success) 13:13:43 INFO - [mozharness: 2017-06-16 20:13:43.379373Z] Running read-buildbot-config step. 13:13:43 INFO - Running pre-action listener: _resource_record_pre_action 13:13:43 INFO - Running main action method: read_buildbot_config 13:13:43 INFO - Using buildbot properties: 13:13:43 INFO - { 13:13:43 INFO - "project": "", 13:13:43 INFO - "product": "firefox", 13:13:43 INFO - "script_repo_revision": "production", 13:13:43 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 13:13:43 INFO - "repository": "", 13:13:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 13:13:43 INFO - "buildid": "20170616111822", 13:13:43 INFO - "pgo_build": "False", 13:13:43 INFO - "basedir": "/builds/slave/test", 13:13:43 INFO - "buildnumber": 3, 13:13:43 INFO - "slavename": "t-yosemite-r7-0010", 13:13:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:13:43 INFO - "platform": "yosemite_r7_devedition", 13:13:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 13:13:43 INFO - "branch": "mozilla-beta", 13:13:43 INFO - "repo_path": "releases/mozilla-beta", 13:13:43 INFO - "revision": "b08a305464d127e25fab19f358fac15fceb52954", 13:13:43 INFO - "stage_platform": "macosx64-devedition", 13:13:43 INFO - "builduid": "ece30b210a834613b688eb410673226c", 13:13:43 INFO - "slavebuilddir": "test" 13:13:43 INFO - } 13:13:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 13:13:43 INFO - Running post-action listener: _resource_record_post_action 13:13:43 INFO - [mozharness: 2017-06-16 20:13:43.382793Z] Finished read-buildbot-config step (success) 13:13:43 INFO - [mozharness: 2017-06-16 20:13:43.382885Z] Running download-and-extract step. 13:13:43 INFO - Running pre-action listener: _resource_record_pre_action 13:13:43 INFO - Running main action method: download_and_extract 13:13:43 INFO - mkdir: /builds/slave/test/build/tests 13:13:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:13:43 INFO - trying https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 13:13:43 INFO - Downloading https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 13:13:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 13:13:44 INFO - Downloaded 1290 bytes. 13:13:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 13:13:44 INFO - Using the following test package requirements: 13:13:44 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 13:13:44 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 13:13:44 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 13:13:44 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 13:13:44 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:13:44 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 13:13:44 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 13:13:44 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 13:13:44 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 13:13:44 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 13:13:44 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:13:44 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 13:13:44 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 13:13:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 13:13:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 13:13:44 INFO - Fetch https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 13:13:46 INFO - Content-Length response header: 59545149 13:13:46 INFO - Bytes received: 59545149 13:14:04 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 13:14:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 13:14:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 13:14:04 INFO - Fetch https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 13:14:06 INFO - Content-Length response header: 59545149 13:14:06 INFO - Bytes received: 59545149 13:14:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 13:14:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 13:14:24 INFO - Fetch https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 13:14:25 INFO - Content-Length response header: 14454470 13:14:25 INFO - Bytes received: 14454470 13:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:25 INFO - trying https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:14:25 INFO - Downloading https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 13:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 13:14:27 INFO - Downloaded 66857615 bytes. 13:14:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:14:27 INFO - mkdir: /builds/slave/test/properties 13:14:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:14:27 INFO - Writing to file /builds/slave/test/properties/build_url 13:14:27 INFO - Contents: 13:14:27 INFO - build_url:https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:14:27 INFO - Running post-action listener: _resource_record_post_action 13:14:27 INFO - Running post-action listener: set_extra_try_arguments 13:14:27 INFO - [mozharness: 2017-06-16 20:14:27.939780Z] Finished download-and-extract step (success) 13:14:27 INFO - [mozharness: 2017-06-16 20:14:27.939935Z] Running populate-webroot step. 13:14:27 INFO - Running pre-action listener: _resource_record_pre_action 13:14:27 INFO - Running main action method: populate_webroot 13:14:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:14:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:14:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:14:27 INFO - '--tppagecycles', 13:14:27 INFO - '7'], 13:14:27 INFO - 'tests': ['tsvgm']}}, 13:14:27 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:14:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:14:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:14:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:14:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:14:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['damp', 'tps']}, 13:14:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'tests': ['damp', 'tps']}, 13:14:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['dromaeo_dom']}, 13:14:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:14:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 13:14:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:14:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 13:14:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'other-e10s': {'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['a11yr', 13:14:27 INFO - 'ts_paint', 13:14:27 INFO - 'tpaint', 13:14:27 INFO - 'sessionrestore', 13:14:27 INFO - 'sessionrestore_no_auto_restore', 13:14:27 INFO - 'tabpaint']}, 13:14:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 13:14:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 13:14:27 INFO - 'bloom_basic_ref']}, 13:14:27 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:14:27 INFO - 'talos_options': ['--mitmproxy', 13:14:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:14:27 INFO - '--firstNonBlankPaint'], 13:14:27 INFO - 'tests': ['quantum_pageload_google', 13:14:27 INFO - 'quantum_pageload_youtube', 13:14:27 INFO - 'quantum_pageload_amazon', 13:14:27 INFO - 'quantum_pageload_facebook']}, 13:14:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['tsvgx', 13:14:27 INFO - 'tsvgr_opacity', 13:14:27 INFO - 'tart', 13:14:27 INFO - 'tscrollx', 13:14:27 INFO - 'cart', 13:14:27 INFO - 'tsvg_static']}, 13:14:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:14:27 INFO - 'tsvgr_opacity', 13:14:27 INFO - 'tart', 13:14:27 INFO - 'tscrollx', 13:14:27 INFO - 'cart', 13:14:27 INFO - 'tsvg_static']}, 13:14:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-e10s'], 13:14:27 INFO - 'tests': ['tp5o']}, 13:14:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:14:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--disable-e10s', 13:14:27 INFO - '--xperf_path', 13:14:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:14:27 INFO - 'tests': ['tp5n']}, 13:14:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:14:27 INFO - 'talos_options': ['--xperf_path', 13:14:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:14:27 INFO - 'tests': ['tp5n']}}, 13:14:27 INFO - 'talos.zip': {'path': '', 13:14:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:14:27 INFO - Running post-action listener: _resource_record_post_action 13:14:27 INFO - [mozharness: 2017-06-16 20:14:27.961237Z] Finished populate-webroot step (success) 13:14:27 INFO - [mozharness: 2017-06-16 20:14:27.961380Z] Running create-virtualenv step. 13:14:27 INFO - Running pre-action listener: _resource_record_pre_action 13:14:27 INFO - Running main action method: create_virtualenv 13:14:27 INFO - Creating virtualenv /builds/slave/test/build/venv 13:14:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:14:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:14:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:14:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:14:28 INFO - Using real prefix '/tools/python27' 13:14:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:14:28 INFO - Installing distribute.............................................................................................................................................................................................done. 13:14:31 INFO - Installing pip.................done. 13:14:31 INFO - Return code: 0 13:14:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:14:31 INFO - Reading from file tmpfile_stdout 13:14:31 INFO - Output received: 13:14:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:14:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:14:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:14:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:31 INFO - 'HOME': '/Users/cltbld', 13:14:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:31 INFO - 'LOGNAME': 'cltbld', 13:14:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:31 INFO - 'MOZ_NO_REMOTE': '1', 13:14:31 INFO - 'NO_EM_RESTART': '1', 13:14:31 INFO - 'PAGER': '/bin/cat', 13:14:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:31 INFO - 'PWD': '/builds/slave/test', 13:14:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:31 INFO - 'SHELL': '/bin/bash', 13:14:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:31 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:31 INFO - 'USER': 'cltbld', 13:14:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:31 INFO - 'XPC_FLAGS': '0x0', 13:14:31 INFO - 'XPC_SERVICE_NAME': '0', 13:14:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:14:32 INFO - Cleaning up... 13:14:32 INFO - Return code: 0 13:14:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:14:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:32 INFO - 'HOME': '/Users/cltbld', 13:14:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:32 INFO - 'LOGNAME': 'cltbld', 13:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:32 INFO - 'MOZ_NO_REMOTE': '1', 13:14:32 INFO - 'NO_EM_RESTART': '1', 13:14:32 INFO - 'PAGER': '/bin/cat', 13:14:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:32 INFO - 'PWD': '/builds/slave/test', 13:14:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:32 INFO - 'SHELL': '/bin/bash', 13:14:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:32 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:32 INFO - 'USER': 'cltbld', 13:14:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:32 INFO - 'XPC_FLAGS': '0x0', 13:14:32 INFO - 'XPC_SERVICE_NAME': '0', 13:14:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:32 INFO - Downloading/unpacking psutil>=3.1.1 13:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:14:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:14:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:14:34 INFO - Installing collected packages: psutil 13:14:34 INFO - Running setup.py install for psutil 13:14:34 INFO - building 'psutil._psutil_osx' extension 13:14:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:14:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:14:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:14:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:14:35 INFO - building 'psutil._psutil_posix' extension 13:14:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:14:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:14:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:14:35 INFO - ^ 13:14:35 INFO - 1 warning generated. 13:14:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:14:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:14:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:14:35 INFO - Successfully installed psutil 13:14:35 INFO - Cleaning up... 13:14:35 INFO - Return code: 0 13:14:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:36 INFO - 'HOME': '/Users/cltbld', 13:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:36 INFO - 'LOGNAME': 'cltbld', 13:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:36 INFO - 'MOZ_NO_REMOTE': '1', 13:14:36 INFO - 'NO_EM_RESTART': '1', 13:14:36 INFO - 'PAGER': '/bin/cat', 13:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:36 INFO - 'PWD': '/builds/slave/test', 13:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:36 INFO - 'SHELL': '/bin/bash', 13:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:36 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:36 INFO - 'USER': 'cltbld', 13:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:36 INFO - 'XPC_FLAGS': '0x0', 13:14:36 INFO - 'XPC_SERVICE_NAME': '0', 13:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:14:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:14:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:14:38 INFO - Installing collected packages: mozsystemmonitor 13:14:38 INFO - Running setup.py install for mozsystemmonitor 13:14:38 INFO - Successfully installed mozsystemmonitor 13:14:38 INFO - Cleaning up... 13:14:38 INFO - Return code: 0 13:14:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:14:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:39 INFO - 'HOME': '/Users/cltbld', 13:14:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:39 INFO - 'LOGNAME': 'cltbld', 13:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:39 INFO - 'MOZ_NO_REMOTE': '1', 13:14:39 INFO - 'NO_EM_RESTART': '1', 13:14:39 INFO - 'PAGER': '/bin/cat', 13:14:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:39 INFO - 'PWD': '/builds/slave/test', 13:14:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:39 INFO - 'SHELL': '/bin/bash', 13:14:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:39 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:39 INFO - 'USER': 'cltbld', 13:14:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:39 INFO - 'XPC_FLAGS': '0x0', 13:14:39 INFO - 'XPC_SERVICE_NAME': '0', 13:14:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:39 INFO - Downloading/unpacking jsonschema==2.5.1 13:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:14:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:40 INFO - Downloading functools32-3.2.3-2.tar.gz 13:14:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:14:41 INFO - warning: no files found matching '*.txt' 13:14:41 INFO - no previously-included directories found matching 'build' 13:14:41 INFO - no previously-included directories found matching 'dist' 13:14:41 INFO - no previously-included directories found matching '.git*' 13:14:41 INFO - Installing collected packages: jsonschema, functools32 13:14:41 INFO - Running setup.py install for functools32 13:14:41 INFO - warning: no files found matching '*.txt' 13:14:41 INFO - no previously-included directories found matching 'build' 13:14:41 INFO - no previously-included directories found matching 'dist' 13:14:41 INFO - no previously-included directories found matching '.git*' 13:14:41 INFO - Successfully installed jsonschema functools32 13:14:41 INFO - Cleaning up... 13:14:41 INFO - Return code: 0 13:14:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:41 INFO - 'HOME': '/Users/cltbld', 13:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:41 INFO - 'LOGNAME': 'cltbld', 13:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:41 INFO - 'MOZ_NO_REMOTE': '1', 13:14:41 INFO - 'NO_EM_RESTART': '1', 13:14:41 INFO - 'PAGER': '/bin/cat', 13:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:41 INFO - 'PWD': '/builds/slave/test', 13:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:41 INFO - 'SHELL': '/bin/bash', 13:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:41 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:41 INFO - 'USER': 'cltbld', 13:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:41 INFO - 'XPC_FLAGS': '0x0', 13:14:41 INFO - 'XPC_SERVICE_NAME': '0', 13:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:14:41 INFO - Cleaning up... 13:14:41 INFO - Return code: 0 13:14:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:14:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:14:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:41 INFO - 'HOME': '/Users/cltbld', 13:14:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:41 INFO - 'LOGNAME': 'cltbld', 13:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:41 INFO - 'MOZ_NO_REMOTE': '1', 13:14:41 INFO - 'NO_EM_RESTART': '1', 13:14:41 INFO - 'PAGER': '/bin/cat', 13:14:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:41 INFO - 'PWD': '/builds/slave/test', 13:14:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:41 INFO - 'SHELL': '/bin/bash', 13:14:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:41 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:41 INFO - 'USER': 'cltbld', 13:14:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:41 INFO - 'XPC_FLAGS': '0x0', 13:14:41 INFO - 'XPC_SERVICE_NAME': '0', 13:14:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:41 INFO - Downloading/unpacking blobuploader==1.2.4 13:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:43 INFO - Downloading blobuploader-1.2.4.tar.gz 13:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:14:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:14:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:44 INFO - Downloading docopt-0.6.1.tar.gz 13:14:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:14:44 INFO - Installing collected packages: blobuploader, requests, docopt 13:14:44 INFO - Running setup.py install for blobuploader 13:14:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:14:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:14:44 INFO - Running setup.py install for requests 13:14:44 INFO - Running setup.py install for docopt 13:14:44 INFO - Successfully installed blobuploader requests docopt 13:14:44 INFO - Cleaning up... 13:14:45 INFO - Return code: 0 13:14:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:14:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:45 INFO - 'HOME': '/Users/cltbld', 13:14:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:45 INFO - 'LOGNAME': 'cltbld', 13:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:45 INFO - 'MOZ_NO_REMOTE': '1', 13:14:45 INFO - 'NO_EM_RESTART': '1', 13:14:45 INFO - 'PAGER': '/bin/cat', 13:14:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:45 INFO - 'PWD': '/builds/slave/test', 13:14:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:45 INFO - 'SHELL': '/bin/bash', 13:14:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:45 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:45 INFO - 'USER': 'cltbld', 13:14:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:45 INFO - 'XPC_FLAGS': '0x0', 13:14:45 INFO - 'XPC_SERVICE_NAME': '0', 13:14:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-rkucfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-8e7S7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-srQmNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-31s8vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-W8xEPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-lkWzu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ZfjrN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:45 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Ks6mux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-dHZzpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-0Wj5un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Q3ecms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-svdh8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-sJGxQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-5jCAAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-oSws0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-WmexMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:46 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Dgakm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:14:47 INFO - Running setup.py install for manifestparser 13:14:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Running setup.py install for mozcrash 13:14:47 INFO - Running setup.py install for mozdebug 13:14:47 INFO - Running setup.py install for mozdevice 13:14:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Running setup.py install for mozfile 13:14:47 INFO - Running setup.py install for mozhttpd 13:14:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:14:47 INFO - Running setup.py install for mozinfo 13:14:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Running setup.py install for mozInstall 13:14:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Running setup.py install for mozleak 13:14:48 INFO - Running setup.py install for mozlog 13:14:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Running setup.py install for moznetwork 13:14:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Running setup.py install for mozprocess 13:14:48 INFO - Running setup.py install for mozprofile 13:14:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:14:48 INFO - Running setup.py install for mozrunner 13:14:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:14:49 INFO - Running setup.py install for mozscreenshot 13:14:49 INFO - Running setup.py install for moztest 13:14:49 INFO - Running setup.py install for mozversion 13:14:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:14:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:14:49 INFO - Cleaning up... 13:14:49 INFO - Return code: 0 13:14:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:14:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:14:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:49 INFO - 'HOME': '/Users/cltbld', 13:14:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:49 INFO - 'LOGNAME': 'cltbld', 13:14:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:49 INFO - 'MOZ_NO_REMOTE': '1', 13:14:49 INFO - 'NO_EM_RESTART': '1', 13:14:49 INFO - 'PAGER': '/bin/cat', 13:14:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:49 INFO - 'PWD': '/builds/slave/test', 13:14:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:49 INFO - 'SHELL': '/bin/bash', 13:14:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:49 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:49 INFO - 'USER': 'cltbld', 13:14:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:49 INFO - 'XPC_FLAGS': '0x0', 13:14:49 INFO - 'XPC_SERVICE_NAME': '0', 13:14:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:14:49 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ebK1TX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Obp0l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-BWFn9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-tofWJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-kfYO1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ULVi_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-B6iw6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-_zJaWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-jw3WkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ziDHW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:14:50 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-Kv9_AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:14:51 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-g90PJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:14:51 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-ldLhxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:14:51 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-geyN4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:51 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-yJPSKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:14:51 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-RL4c6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:14:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:14:51 INFO - Running setup.py (path:/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/pip-mHylR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:14:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:53 INFO - Downloading blessings-1.6.tar.gz 13:14:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:14:53 INFO - Installing collected packages: blessings 13:14:53 INFO - Running setup.py install for blessings 13:14:53 INFO - Successfully installed blessings 13:14:53 INFO - Cleaning up... 13:14:53 INFO - Return code: 0 13:14:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:14:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:53 INFO - Reading from file tmpfile_stdout 13:14:53 INFO - Current package versions: 13:14:53 INFO - blessings == 1.6 13:14:53 INFO - blobuploader == 1.2.4 13:14:53 INFO - docopt == 0.6.1 13:14:53 INFO - functools32 == 3.2.3-2 13:14:53 INFO - jsonschema == 2.5.1 13:14:53 INFO - manifestparser == 1.1 13:14:53 INFO - mozInstall == 1.13 13:14:53 INFO - mozcrash == 1.0 13:14:53 INFO - mozdebug == 0.1 13:14:53 INFO - mozdevice == 0.51 13:14:53 INFO - mozfile == 1.2 13:14:53 INFO - mozhttpd == 0.7 13:14:53 INFO - mozinfo == 0.10 13:14:53 INFO - mozleak == 0.1 13:14:53 INFO - mozlog == 3.4 13:14:53 INFO - moznetwork == 0.27 13:14:53 INFO - mozprocess == 0.25 13:14:53 INFO - mozprofile == 0.28 13:14:53 INFO - mozrunner == 6.13 13:14:53 INFO - mozscreenshot == 0.1 13:14:53 INFO - mozsystemmonitor == 0.3 13:14:53 INFO - moztest == 0.8 13:14:53 INFO - mozversion == 1.4 13:14:53 INFO - psutil == 3.1.1 13:14:53 INFO - requests == 1.2.3 13:14:53 INFO - wsgiref == 0.1.2 13:14:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:14:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:53 INFO - 'HOME': '/Users/cltbld', 13:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:53 INFO - 'LOGNAME': 'cltbld', 13:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:53 INFO - 'MOZ_NO_REMOTE': '1', 13:14:53 INFO - 'NO_EM_RESTART': '1', 13:14:53 INFO - 'PAGER': '/bin/cat', 13:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:53 INFO - 'PWD': '/builds/slave/test', 13:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:53 INFO - 'SHELL': '/bin/bash', 13:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:53 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:53 INFO - 'USER': 'cltbld', 13:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:53 INFO - 'XPC_FLAGS': '0x0', 13:14:53 INFO - 'XPC_SERVICE_NAME': '0', 13:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:14:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:14:53 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:14:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:14:55 INFO - Downloading marionette_driver-1.4.0.tar.gz 13:14:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 13:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:14:55 INFO - Installing collected packages: marionette-driver 13:14:55 INFO - Running setup.py install for marionette-driver 13:14:55 INFO - Successfully installed marionette-driver 13:14:55 INFO - Cleaning up... 13:14:55 INFO - Return code: 0 13:14:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:14:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108959c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10895d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe00356ef70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:14:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:14:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:14:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:14:55 INFO - 'HOME': '/Users/cltbld', 13:14:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:14:55 INFO - 'LOGNAME': 'cltbld', 13:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:14:55 INFO - 'MOZ_NO_REMOTE': '1', 13:14:55 INFO - 'NO_EM_RESTART': '1', 13:14:55 INFO - 'PAGER': '/bin/cat', 13:14:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:14:55 INFO - 'PWD': '/builds/slave/test', 13:14:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:14:55 INFO - 'SHELL': '/bin/bash', 13:14:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:14:55 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:14:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:14:55 INFO - 'USER': 'cltbld', 13:14:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:14:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:14:55 INFO - 'XPC_FLAGS': '0x0', 13:14:55 INFO - 'XPC_SERVICE_NAME': '0', 13:14:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:14:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:14:55 INFO - Cleaning up... 13:14:55 INFO - Return code: 0 13:14:55 INFO - Running post-action listener: _resource_record_post_action 13:14:55 INFO - Running post-action listener: _start_resource_monitoring 13:14:55 INFO - Starting resource monitoring. 13:14:55 INFO - [mozharness: 2017-06-16 20:14:55.917025Z] Finished create-virtualenv step (success) 13:14:55 INFO - [mozharness: 2017-06-16 20:14:55.917969Z] Running install step. 13:14:55 INFO - Running pre-action listener: _resource_record_pre_action 13:14:55 INFO - Running main action method: install 13:14:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:14:56 INFO - Reading from file tmpfile_stdout 13:14:56 INFO - Detecting whether we're running mozinstall >=1.0... 13:14:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:14:56 INFO - Reading from file tmpfile_stdout 13:14:56 INFO - Output received: 13:14:56 INFO - Usage: mozinstall [options] installer 13:14:56 INFO - Options: 13:14:56 INFO - -h, --help show this help message and exit 13:14:56 INFO - -d DEST, --destination=DEST 13:14:56 INFO - Directory to install application into. [default: 13:14:56 INFO - "/builds/slave/test"] 13:14:56 INFO - --app=APP Application being installed. [default: firefox] 13:14:56 INFO - mkdir: /builds/slave/test/build/application 13:14:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 13:15:17 INFO - Reading from file tmpfile_stdout 13:15:17 INFO - Output received: 13:15:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:15:17 INFO - Running post-action listener: _resource_record_post_action 13:15:17 INFO - [mozharness: 2017-06-16 20:15:17.486792Z] Finished install step (success) 13:15:17 INFO - [mozharness: 2017-06-16 20:15:17.486939Z] Running setup-mitmproxy step. 13:15:17 INFO - Running pre-action listener: _resource_record_pre_action 13:15:17 INFO - Running main action method: setup_mitmproxy 13:15:17 INFO - Skipping: mitmproxy is not required 13:15:17 INFO - Running post-action listener: _resource_record_post_action 13:15:17 INFO - [mozharness: 2017-06-16 20:15:17.487587Z] Finished setup-mitmproxy step (success) 13:15:17 INFO - [mozharness: 2017-06-16 20:15:17.487697Z] Running run-tests step. 13:15:17 INFO - Running pre-action listener: _resource_record_pre_action 13:15:17 INFO - Running main action method: run_tests 13:15:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:15:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:15:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:15:17 INFO - 2017-06-16 13:15:17.502 screenresolution[1623:7527] starting screenresolution argv=screenresolution get 13:15:17 INFO - 2017-06-16 13:15:17.504 screenresolution[1623:7527] Display 0: 1600x1200x32@60 13:15:17 INFO - 2017-06-16 13:15:17.510 screenresolution[1624:7529] starting screenresolution argv=screenresolution list 13:15:17 INFO - Available Modes on Display 0 13:15:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:15:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:15:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:15:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:15:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:15:17 INFO - Intel Iris: 13:15:17 INFO - Chipset Model: Intel Iris 13:15:17 INFO - Type: GPU 13:15:17 INFO - Bus: Built-In 13:15:17 INFO - VRAM (Dynamic, Max): 1536 MB 13:15:17 INFO - Vendor: Intel (0x8086) 13:15:17 INFO - Device ID: 0x0a2e 13:15:17 INFO - Revision ID: 0x0009 13:15:17 INFO - Displays: 13:15:17 INFO - Display: 13:15:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:15:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:15:17 INFO - Display Serial Number: 1600x1200 60 13:15:17 INFO - Main Display: Yes 13:15:17 INFO - Mirror: Off 13:15:17 INFO - Online: Yes 13:15:17 INFO - Rotation: Supported 13:15:17 INFO - Return code: 0 13:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:15:17 INFO - Python 2.7.3 13:15:17 INFO - Return code: 0 13:15:17 INFO - grabbing minidump binary from tooltool 13:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:15:17 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086afd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086a0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:15:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:15:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:15:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:15:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:15:17 INFO - Return code: 0 13:15:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:15:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:15:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:15:17 INFO - ENV: RUST_BACKTRACE is now 1 13:15:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:15:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ye2DmR6h3p/Render', 13:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:15:17 INFO - 'HOME': '/Users/cltbld', 13:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:15:17 INFO - 'LOGNAME': 'cltbld', 13:15:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:15:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:15:17 INFO - 'MOZ_NO_REMOTE': '1', 13:15:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:15:17 INFO - 'NO_EM_RESTART': '1', 13:15:17 INFO - 'PAGER': '/bin/cat', 13:15:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:15:17 INFO - 'PWD': '/builds/slave/test', 13:15:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:15:17 INFO - 'RUST_BACKTRACE': '1', 13:15:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:15:17 INFO - 'SHELL': '/bin/bash', 13:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.An2TUFSl8Y/Listeners', 13:15:17 INFO - 'TMPDIR': '/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/', 13:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:15:17 INFO - 'USER': 'cltbld', 13:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:15:17 INFO - 'XPC_FLAGS': '0x0', 13:15:17 INFO - 'XPC_SERVICE_NAME': '0', 13:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:15:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:15:18 INFO - mozversion application_buildid: 20170616111822 13:15:18 INFO - mozversion application_changeset: b08a305464d127e25fab19f358fac15fceb52954 13:15:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:15:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:15:18 INFO - mozversion application_name: Firefox 13:15:18 INFO - mozversion application_remotingname: firefox-dev 13:15:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:15:18 INFO - mozversion application_vendor: Mozilla 13:15:18 INFO - mozversion application_version: 55.0 13:15:18 INFO - mozversion platform_buildid: 20170616111822 13:15:18 INFO - mozversion platform_changeset: b08a305464d127e25fab19f358fac15fceb52954 13:15:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:15:18 INFO - mozversion platform_version: 55.0 13:15:18 INFO - using testdate: 1497644118 13:15:18 INFO - actual date: 1497644118 13:15:18 INFO - starting webserver on 'localhost:49227' 13:15:18 INFO - SUITE-START | Running 2 tests 13:15:18 INFO - TEST-START | dromaeo_css 13:15:18 INFO - operating with platform_type : mac_ 13:15:18 INFO - Initialising browser for dromaeo_css test... 13:15:18 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmp_Mfdql/profile 13:15:18 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 13:15:51 INFO - TEST-INFO | 1634: killed by SIGTERM 13:15:51 INFO - Browser initialized. 13:15:51 INFO - Running cycle 1/1 for dromaeo_css test... 13:15:51 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmp_Mfdql/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:15:53 INFO - PID 1643 | 2017-06-16 13:15:53.425 plugin-container[1644:8097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 13:15:53 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:53 INFO - PID 1643 | 2017-06-16 13:15:53.425 plugin-container[1644:8097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a17, name = 'com.apple.CFPasteboardClient' 13:15:53 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:53 INFO - PID 1643 | 2017-06-16 13:15:53.425 plugin-container[1644:8097] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:15:54 INFO - PID 1643 | 2017-06-16 13:15:54.150 plugin-container[1645:8142] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 13:15:54 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:54 INFO - PID 1643 | 2017-06-16 13:15:54.150 plugin-container[1645:8142] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 13:15:54 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:54 INFO - PID 1643 | 2017-06-16 13:15:54.150 plugin-container[1645:8142] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:16:03 INFO - PID 1643 | RSS: Main: 214790144 13:16:03 INFO - PID 1643 | 13:19:08 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 13:19:10 INFO - PID 1643 | RSS: Main: 231272448 13:19:10 INFO - PID 1643 | 13:22:12 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 13:22:13 INFO - PID 1643 | RSS: Main: 241446912 13:22:13 INFO - PID 1643 | 13:25:14 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 13:25:15 INFO - PID 1643 | RSS: Main: 242941952 13:25:15 INFO - PID 1643 | 13:28:17 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 13:28:18 INFO - PID 1643 | RSS: Main: 245063680 13:28:18 INFO - PID 1643 | 13:31:20 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 13:31:21 INFO - PID 1643 | RSS: Main: 245723136 13:31:21 INFO - PID 1643 | 13:34:22 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 13:34:23 INFO - PID 1643 | RSS: Main: 243662848 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | __start_tp_report 13:34:23 INFO - PID 1643 | _x_x_mozilla_page_load 13:34:23 INFO - PID 1643 | _x_x_mozilla_page_load_details 13:34:23 INFO - PID 1643 | |i|pagename|runs| 13:34:23 INFO - PID 1643 | |0;dojo.html;3535.4166562517185;3555.71554275658;3569.4467357559574;3610.512580801593;3622.4928510008585;5502.80740174094;6136.978641397139;6222.906656400152;6260.906086408701;6340.58786178898;6292;6307.400796924297;6330.689298406071;6340.365963403658;6350.110984462179;5467.644603100795;5530.834074977757;5553.805616803413;5568.081266591008;5579.832605021836;5549.667019978814;5565.193047008182;5574.832755017335;5580.330360356763;5594.132909399029;5417.566594672417;5440.401555828856;5446.319210098738;5464.98897703926;5475.397706252349;4639.353029674451;5530.778768849241;5538.833834984957;5583.8883222335735;5600.0759874620635;5436;5446.945530544684;5468.316460442445;5506.697131657757;5562.16567514876;6292.5280603954425;6293.496520278412;6296.68516574174;6328.145700330442;6328.367163283681;6610.537262391635;6722;6842.726290948356;6847.691853866588;6868.381845633917;6810.829729256809;6902.171739391305;6940.6529673516125;6949.965250173767;6951.93048069523;6061.636301821904;6426.74293028283;6481.578697384655;6486.772962946275;6488.351164883474;6875.346842050098;6975.79072627822;6989.231184569693;6997.895031574429;7154.248803875622;6564.638944857983;6602.966985165043;6609.372109649672;6630.303818099127;6719.496037797087;6885.51801373899;6889.483288753364;6911.308869113149;6915.273896240923;6918.446524278046;6756.358145976124;6778.22050464193;6779.186497620317;6784.050232967388;6794.082798822095;6564.9671751640935;6670.833229169212;6705.865882682319;6781.423578995742;6826.078479405217;5474.315710536183;5476.91784623231;5493.10869077588;5498.45015498455;5537.086380747131;4849.2726091085915;4870.780814863339;4924.901501969941;4973.651844370859;4982.800687972441;5718.370979192286;6501.1548498694865;6515.15303010615;6515.348465153446;6542.345765423514;6989.475789315821;7000.544964577286;7006.4745144114395;7028.4025857801635;7042.366187043191;5672.631278966854;6064.636121832616;6265.530085243534;6307.842303942346;6319.526035547352;5180.6891586504935;5636.802711905064;6055.666938318434;6110.755569777248;6160.661163636043;7060.293970602898;7093.503454758117;7099.858002840017;7127.2516385778745;7128.964355178191;6934.757283495053;6938.375546200866;6971.8605627886145;6989.091418115612;7021.719131234693;7077.752278670222;7086.50394472382;7107.040549525748;7118;7120.1099862517185;6621.139251897415;6685.69914353865;6706.966465167643;6734.764283250259;6799.5920244785475;6955.269696681776;6957.026016357613;6961.338672826073;6986.9650651746415;7001.789946301619;5617.606767526234;6738.191417029988;6739.93260067393;6745.325467453215;6766.830829229309;6511.804645860631;6523.869522609616;6605.504587156079;6726.596404215763;6751.459883209233;6430.324815894263;6438.195225596801;6444.258910225384;6446.323136070645;6550.475961922939;6710.261871194067;6734.158230221223;6760.695768690519;6776.15298087739;6782.525223234326;6609.834754131185;6627.138471998609;6633.104530888461;6638.402543771083;6638.83402914931 13:34:23 INFO - PID 1643 | |1;ext.html;669.1969636436278;782.8629989751792;800.7837883771379;811.8335741173061;824.0441088337528;36685.08287292819;37395.62604373959;37556.624433755656;37556.81221593888;37557.43663845045;36660.63339366605;36985.81507092468;36986.8150659247;37003.814980925126;37056.25887482249;7363.5213711108745;7497.962510187442;7501.324880760731;7503.624818759067;7526.7741967740785;7390.519616224955;7497.2877576630135;7502.249775022508;7508.587027713474;7521.5111017783665;7462.664180111881;7470.551766894004;7499.400047996147;7513.173550909396;7514.924850751478;7448.441366897478;7470.47706660534;7486.925130748705;7875;8092.554909479976;7975.561344126071;8046.919530804676;8051.838963220703;8053.275205231499;8076.434649574532;34727.9581612552;34846.651533484845;35227.82386088053;35276.823615882015;35293.64706352955;3579.1231148368815;3614.3855544557487;3615.8915232542804;3617.1680513482047;3624.0577449862963;28680.709368078486;28709.28226794347;28833.279168020967;28855.711442885513;28860;3002.8198308101582;3046.512557990711;3090.5673205751214;3091.165385345944;3106.4408406486823;209028;250710;251877;302400;306458.46770766005;37827.10864456766;46622.53377466182;46703.76648116806;46729.29906051412;46796.5320346799;49427;49494;49838.750806245735;49952;50006.24990625143;15122.54632361031;15140.772888406682;15282.847171528143;15308.770368444482;15310.770338444932;12551.12142150059;12553.560625378068;12581.811272830915;12614.684632884251;12635.557755478514;13354.332283385791;13358.866411335957;13383.866161338457;13427.12723672958;13475.528356507672;5953.374895635982;5974.3428222895445;6025.0359942409;6029.78895738647;6034.9396506035255;944.6929747831982;1005.7737009173023;1010.5907107621425;1011.9949400252951;1072.495926914349;22767.7723222769;22922.31233063011;23313;23457.41356466102;24462.633060504108;1009.8485227215976;1038.4651904269308;1051.4847724614883;1094.5020015892908;1103.9172062095374;12935.835774780315;13508.459661613622;16187.271572779253;16543.83456165447;16779.328826846788;365843;366219;368050;368457;369202;55567.44432555704;55630.443695563335;55759.72120139373;56118.71940640352;56511.43488565062;1483.5845963130341;1523.1546491697216;1545.806774153231;1547.8142622885325;1549.1634517360724;27952.860235698692;29232.83068677229;29521.81912723553;29778.702212977594;29987.850060749555;50305.49694503008;50719.49280507147;50749;50763.238551422495;50958;326325;326837;327216;328021;329112;29553.408931820813;29952.550711738906;30110.849445752632;34126.31747364989;35809.925702228975;41366;41991.370129447474;42089.57910420857;43992;44047.339289910036;28065.57901631518;28069.43861122807;28790.42419151647;29058.128256152348;30566.93015744438;29459;29682.703172967995;30000.24999375033;30012.549811753277;30161.698383016766;34169.145771355914;36024.459633105;36043;36049.27901442009;36135.10919126516;26400.143195088975;29370;29408;30826.53760193554;30896.073117806503;22085.55828883381;23815.047398104576;23949.04203831827;24095;24186.274411767674 13:34:23 INFO - PID 1643 | |2;jquery.html;478.55972505295125;504.57363527819;518.3158231134898;520.9086962812905;537.3846945247694;21568.92155392228;21689.24087656932;21975.560488790215;22341.888290558563;22350.21774237902;23731.76268237317;23776.52446951064;24004.91977860996;24005.75994240057;24032;6242.219722534684;6428.646424446653;6429.614223146603;6445.387688169616;6456.22525296965;6319.968400157993;6383.585066970632;6432.5497215194955;6436.35636436355;6436.903446448284;5747.942520574804;6283.057541368808;6301.527385446087;6302.779402720906;6314.431701146896;6318.368163183668;6335.778247761329;6359.109724638554;6368.2994870564435;6371.808845734589;5859.941400585983;5863.120531920204;5863.941360586382;5869.647821130746;5880.558958078119;28572.285692857637;28935.855320723473;29687.109386718217;29958.700412996026;30134;2097.811196992278;2317.5480781247666;2340.9297721068397;2344.991653588956;2348.0373047050625;18742.906285468485;19086.141123649468;19122.80877191238;19275.51811204717;19293.807061929343;2190.8028277454946;2209.2709405896017;2258.4128126687015;2265.286434773058;2286.565552545011;172397;181187;181392;181681;195655.02172489188;13890.305484725723;14226.297003569496;15037.248137593077;15073.924630376998;15384.15387153695;20057;20074.899625501777;20110.296139635335;20138.993050347424;20208.393748187576;10271.383716977005;10289.53697083633;10343.689689309333;10347.793044139074;10409.5836166554;9733.415995040321;9814.362066465657;9826.312158149003;9826.901730982741;9828.508574571242;7216.8917466238045;7395.51929124616;7988.201179881965;8863.423877447945;8945.731628051039;2485.4780496096023;2496.7752902238885;2497.400623850263;2498.6252062190815;2499.487605040962;876.3602570123845;931.2596485793811;964.9276304277207;976.2385339435252;1006.7147575004699;20932.372028838854;21013.369598912057;21186.576268474546;21737.565248694937;21794.564108717736;979.1677074486629;985.6698006168014;1004.5133202859809;1035.5081336365317;1050.4432650695144;10740.764812046715;11013;11026.338419694845;11370.147238957112;11484.483198256099;528175;541112;549242;566013;567729;139777.99999999796;143795.2810235942;143993;144078;148083.5191648091;1174.7650469905882;1190.7082764722697;1205.6202296276645;1213.605578187075;1219.9512019519298;17597.208125634632;19104.522386940436;19117.90441047786;19352;19604.803951960297;60914.78170436655;61164;61207.387926120166;63212;66722.66554668783;82503.58748206221;83802;86332;86493;87167.69248461405;11489.368084755419;11908.583199587973;12163.2702037878;12198.756024879276;12222.961048310994;18468.353607623667;18531;18548.907255463637;18559.25762969466;18702.97133657662;14807.555773326818;14837.703245935238;14905.552833415015;14975.251237438302;15058.472953446577;12210.26738395699;12296;12556.686082848002;13167.736645267232;13455.654434556465;14687.41250349974;14925.328360223599;15013.849861501245;15125.924370378078;15132.319045643193;11781.116416268986;11880.643580692593;12004.819927701266;12015.158938874194;12086.637400877988;14680.192589407685;14805.703885922436;14821.25893705332;14882.18148001827;14901.403943842124 13:34:23 INFO - PID 1643 | |3;mootools.html;1425.1805212003098;1449.8115245018146;1471.6173794813346;1479.1273148842183;1484.6140003599069;10981.286216395929;12418.937905310471;12810.80783788244;12922.870771292284;12945.352732363392;13326.733465330688;13740.58778236652;14208.857911420882;14217.431302747878;14245.78631320531;2743.917682469524;2810.0445848411537;2817.2252630526564;2819.647544056993;2820.8589570521494;2779.069011881022;2814.6481689788775;2822.421403612265;2823.8446885421295;2826.5053615617185;2610.6946526736633;2617.7958139255966;2682.7183145769704;2778.527650299444;2823.110720123165;2836.3901761121356;2853.843038632864;2855.971440285591;2860.599516067752;2863.3700585871075;2776.7778577713734;2785.9164225073077;2796.4546913351905;2803.6074949506883;2807.3122085089285;13436.193828370231;13752.724945501133;13760.587182384545;13790;13797.310134493235;7572.848543029164;7860.017497812773;7863.56750378729;7866.960665196694;7880.211978802131;4753.928691069636;4895.926561101586;4923.75381230937;4994.750262486861;5039.571636410909;1234.993825030878;1382.9377678004514;1385.8336999560117;1387.5906607550796;1408.6689627937528;20469.488262793402;20480.97595120238;20602.072906719233;20650.690239646417;20651.896740516502;5675.971620141955;6645.767398141042;6713.362230588086;6845.418139458102;6846.110005699227;14281;14534.418623255162;14571.85428145705;14572.927135364467;14584.124952502883;7408.85182296351;7525.661345239368;7563.432742544332;7567.508111972704;7579.431542634325;6826.4538836892925;6940.826479337956;6969.756058537927;7011.474139439562;7037.8240543985785;4572.4055872736335;4734.502877197913;4841.983183531498;5333.439988801198;5361.276227709287;1537.3312609015113;1544.9845501544842;1544.9915110356492;1560.492839827038;1561.4691005058107;814.1858141858141;829.2371018662844;843.2747836860295;847.2798121596717;856.5973992223645;8021.598920054091;8174.3051840593025;8290.590599598083;8473.195046470575;8474.449160804528;862.6376921692904;970.3886551472668;1010.6008126789877;1010.8534262532039;1019.0980981831133;4138.813753381105;4787;5015.949840501621;5298.258243845865;5362.356517217959;18279.63440731178;18781.15484803187;18841.62316753657;18860.434186974413;18920.71618925717;18183.818161818213;18547.536311592048;18853.963032033094;18932.62134757297;18962.336318228794;9349.766255843659;9471.289653276031;9503.382280151767;9516.571754271074;9524.380915240627;6682.899756503559;7703.38372930153;7851.450398472052;8042.638081286239;8219.219174178443;16553.337866485206;17282.22229999628;17295.481135565955;17310.394136205174;17438;16434.506964791075;17082.231299591298;17620.823791762432;17833.643327133643;17923.92456452565;10700.678979630624;10829.945850271013;10924.945375273392;10956.28784129029;10979;12335.629931102081;12427.75144497123;12580.30808305552;12660.366981651065;12673;10101.449561967325;10123.898761012295;10275.486225688835;10433.582656693647;10497.2127090467;9837.508124593885;9905.702828915144;10165.745856353651;10214.927432619765;10283.742906427398;9212.723618291462;9429;9442.38624489406;9444.763880902758;9459.10138536838;6584.407403333723;6701.865962680621;6796.388325050571;7024.894626580503;7032.26161253061;6649.700763465559;6848.212455567674;6935.791926242221;7133.429325654039;7197.424206063607 13:34:23 INFO - PID 1643 | |4;prototype.html;399.5485101834927;433.5014733056986;458.92657174852025;469.7956388970798;481.3237401450958;6249.31257561669;6480.31956644552;6539.574927629701;6561;6617.974213996826;4831.704940153434;6298.079085878805;6372.107904893307;6380.298167201604;6438.839029024277;4218.282891908383;5002.674826136307;5039.798408063692;5042.722650254234;5056.620753443488;4518.615917646987;4672.929906051412;5018.2974383586325;5043.899122017558;5047.116754567936;4561.29299958507;4954.430240522332;4971.950280497203;4992.775325110379;5004.199328107504;4567.611753000999;4734.0295239476;4761;4767.952320476786;4774.665773395864;4200.957990420118;4201.516825565038;4207.936880946788;4210;4214.641755450791;5952.702364881782;6227.844303892393;6242.094896240065;6257.871064467766;6292.622442653455;5240.973795131038;5691.146328050784;5705.486506214419;5711.171880077365;5754.050581654022;6327.715252813635;6939.23668396481;6956.339147780953;7026.910828821541;7300.4159667226495;4221.472315960505;4260.275753121946;4719.07977944301;4823.565879070866;4872.756362181912;6066.6663333516635;6453.257875344349;7088.794904866185;7289.817754556126;7385.4460915431555;4361.258586040381;4949.663590830455;4952.405711314594;4955.281484184845;4958.528939750718;5012.1980483122525;5032.597392208614;5045.217991211368;5049.368828896388;5061.417936937226;5691.8292451226525;5708.71456427177;5711.343195532567;5744.224529688522;5775.8556036099435;5550.833474995757;5814.993827129633;6980.616066116311;6987.475939304572;7013.580314791388;1713.7515060316184;1730.4981555348809;1746;1898.003548137239;1904.7523821903342;1067.2475904487474;1333.7199188170398;1364.597443754095;1369.2469141971876;1410.978835317471;4858.78135483904;5180.352455943007;5447;5480.314960629921;6371.076193951943;6558.344165583403;6579.24338701046;7240.96379518099;7276;7297.927020729831;5701.265670985428;6516.413522782877;6538.8365290868105;6570.540062195696;6605.669716514155;3575.8212089395424;3735.14091758897;3744.7753134811996;3755.4930084438797;3759.642833930772;6206.882761103045;6684.9331506685285;6831.316868313228;7363.631818409058;7422.958686361294;6849.4177994871;7368.484206105629;7393.704251829974;7415.221401752846;7415.703371865065;6444.355564443518;7363.558186508827;7372.594507302043;7402.33378995893;7421.369183619345;6540.869182616416;7176.7488137914925;7187.496875218684;7189.3889019431845;7236.34872861445;6165.445109940127;6239.0953311770445;7006.229314775473;7049.2155598537265;7086.929130708627;6349.825282322785;6438.6136831790245;7346.889796652948;7375;7388.519746216479;4365.410669559569;4604.293543697356;4908.6073114151495;4913.778879950482;4941.888075183198;6213.819374318865;6302.5189080565115;7136.964315178391;7146.642667866482;7162.964185179041;6781.423578995643;6877.58734475933;6920.757773477904;6939.201991771011;6957.478189135936;6054.636721796706;6896.206936202401;6905.274946130785;6934.237233904166;6934.780870121513;6729.831754206184;6735.865282694221;6742.988551717282;6753.864922701617;6762.932370676231;6861.4853885957755;6920.550164239308;6936.341047600672;6937.618430986344;6980.825479363057;5663.32040155184;6691.431228345645;6700.731970721116;6709.932900671126;6802.075107703735 13:34:23 INFO - PID 1643 | |5;yui.html;748.3115533708989;804.3685706720225;821.9917800821989;839.3033781960974;840.0843081041663;16763.497095087136;17248.310067597296;17257.91371043143;17277.222524986373;17293.39473118441;17222.224999875005;18446.631067378643;18511.444656660322;18511.90744046278;18558.97925614094;951.3578334624118;953.6995846308425;965.8164948659768;967.9032096790299;969.4522594733988;981.2150279776187;982.2878413150445;982.7444864335289;984.4782265399314;989.6833013435703;967.8257913575553;1020.9438480883548;1031.0153803118005;1035.6427032673716;1036.6941752183125;1007.5113569918584;1010.706895000449;1016.1413605503337;1023.1150055202271;1024.472396715692;749.2058418076856;750.7334896111911;750.8122969257686;751.6166754954958;752.4995877741295;17208.741868871977;17290;17383.043932583703;17452.738208926876;17490.21294041771;17520;17872.821271787376;18107.909460452745;18192.90842549438;18248.543786405313;14858.925705371405;14988.025778324374;15034.699306013928;15047.097174169585;15058.849411505855;3163.066895265902;3474.513568100468;3589.8743543976097;3594.856205751767;3609.855605775766;150214;152327;155765;156607.2169639156;157788;11515.790841961641;12736.872631273569;12956.611301660965;13067.281299528617;13081.607551773463;22593.322200334016;23333.76666233316;24618.261452156465;25044.624330635055;25600.231993040237;7895.289423951872;7981.8802717958115;8007.319377852948;8010.679572817138;8038.557879316577;4082.4488694026522;4156.210320039183;4165.541790403053;4191.308434108399;4193.706440549132;4748.501407352247;4809.326694262806;4812.8796780080775;4814.638902082358;4817.373741413594;850.2475309351269;856.6145234644434;918.5774543709969;959.5442164971582;977.2328721953232;573.674811186153;591.6627522312327;638.5370606310388;661.3089321658878;671.9899201511981;10158.695239142837;11055.834162487568;11299;11345.31928084301;12918.289494077728;627.4290395739855;630.391672036487;644.8484606117559;656.3600489522772;657.6350125680293;4759.881002974884;5581.246531718166;5626.634268772516;5652.943470565242;5802.274715660543;149728;154022;154956.4504354942;156133.43866561417;159797.40202598058;127582.36208818897;132686;133641;134080.32959835138;139645;96743.51628241813;101048.49475752574;107618.92381076246;107804.46097769462;116989.41505292419;11611.6516504505;12103.152779305716;12346.814797778221;12379;12691.175073620185;43828.780856095516;46795.89418476245;47440;48288.034239314315;49046.75476622594;62146.68926655338;62280.377196227455;65335.346646534825;65638.34361656323;66467.00299495415;14390.640233994234;14485.92757036208;14545.200013999332;14565.635859103184;14643.56069317922;18151.45545633633;18257.817421826145;18488.815111848708;19129.139188736262;19472.707909381086;15330.616734581725;15935;16493.83506164971;18172.818271817643;18334.449966501026;15569.221538923235;16685.416010439578;17776;17930.462086137435;18079.276828926697;16703.58241043935;16856.66286674235;17019.14904254807;17080.145992700564;17583.472495825146;11345;11522.53909843597;11907.464164112462;12070.818937715765;12117.212381195193;12110.818337725117;13358.398872050586;14282.57152285433;14502.419903203754;14559.85440145585 13:34:23 INFO - PID 1643 | __end_tp_report 13:34:23 INFO - PID 1643 | __start_cc_report 13:34:23 INFO - PID 1643 | _x_x_mozilla_cycle_collect,403 13:34:23 INFO - PID 1643 | __end_cc_report 13:34:23 INFO - PID 1643 | __startTimestamp1497645263241__endTimestamp 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | ------- Summary: start ------- 13:34:23 INFO - PID 1643 | Number of tests: 6 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6285.22 Median:6550.48 stddev:775.78 (11.8%) stddev-sans-first:747.57 13:34:23 INFO - PID 1643 | Values: 3535.4 3555.7 3569.4 3610.5 3622.5 5502.8 6137.0 6222.9 6260.9 6340.6 6292.0 6307.4 6330.7 6340.4 6350.1 5467.6 5530.8 5553.8 5568.1 5579.8 5549.7 5565.2 5574.8 5580.3 5594.1 5417.6 5440.4 5446.3 5465.0 5475.4 4639.4 5530.8 5538.8 5583.9 5600.1 5436.0 5446.9 5468.3 5506.7 5562.2 6292.5 6293.5 6296.7 6328.1 6328.4 6610.5 6722.0 6842.7 6847.7 6868.4 6810.8 6902.2 6940.7 6950.0 6951.9 6061.6 6426.7 6481.6 6486.8 6488.4 6875.3 6975.8 6989.2 6997.9 7154.2 6564.6 6603.0 6609.4 6630.3 6719.5 6885.5 6889.5 6911.3 6915.3 6918.4 6756.4 6778.2 6779.2 6784.1 6794.1 6565.0 6670.8 6705.9 6781.4 6826.1 5474.3 5476.9 5493.1 5498.5 5537.1 4849.3 4870.8 4924.9 4973.7 4982.8 5718.4 6501.2 6515.2 6515.3 6542.3 6989.5 7000.5 7006.5 7028.4 7042.4 5672.6 6064.6 6265.5 6307.8 6319.5 5180.7 5636.8 6055.7 6110.8 6160.7 7060.3 7093.5 7099.9 7127.3 7129.0 6934.8 6938.4 6971.9 6989.1 7021.7 7077.8 7086.5 7107.0 7118.0 7120.1 6621.1 6685.7 6707.0 6734.8 6799.6 6955.3 6957.0 6961.3 6987.0 7001.8 5617.6 6738.2 6739.9 6745.3 6766.8 6511.8 6523.9 6605.5 6726.6 6751.5 6430.3 6438.2 6444.3 6446.3 6550.5 6710.3 6734.2 6760.7 6776.2 6782.5 6609.8 6627.1 6633.1 6638.4 6638.8 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:46805.25 Median:35037.24 stddev:85126.25 (243.0%) stddev-sans-first:85294.57 13:34:23 INFO - PID 1643 | Values: 669.2 782.9 800.8 811.8 824.0 36685.1 37395.6 37556.6 37556.8 37557.4 36660.6 36985.8 36986.8 37003.8 37056.3 7363.5 7498.0 7501.3 7503.6 7526.8 7390.5 7497.3 7502.2 7508.6 7521.5 7462.7 7470.6 7499.4 7513.2 7514.9 7448.4 7470.5 7486.9 7875.0 8092.6 7975.6 8046.9 8051.8 8053.3 8076.4 34728.0 34846.7 35227.8 35276.8 35293.6 3579.1 3614.4 3615.9 3617.2 3624.1 28680.7 28709.3 28833.3 28855.7 28860.0 3002.8 3046.5 3090.6 3091.2 3106.4 209028.0 250710.0 251877.0 302400.0 306458.5 37827.1 46622.5 46703.8 46729.3 46796.5 49427.0 49494.0 49838.8 49952.0 50006.2 15122.5 15140.8 15282.8 15308.8 15310.8 12551.1 12553.6 12581.8 12614.7 12635.6 13354.3 13358.9 13383.9 13427.1 13475.5 5953.4 5974.3 6025.0 6029.8 6034.9 944.7 1005.8 1010.6 1012.0 1072.5 22767.8 22922.3 23313.0 23457.4 24462.6 1009.8 1038.5 1051.5 1094.5 1103.9 12935.8 13508.5 16187.3 16543.8 16779.3 365843.0 366219.0 368050.0 368457.0 369202.0 55567.4 55630.4 55759.7 56118.7 56511.4 1483.6 1523.2 1545.8 1547.8 1549.2 27952.9 29232.8 29521.8 29778.7 29987.9 50305.5 50719.5 50749.0 50763.2 50958.0 326325.0 326837.0 327216.0 328021.0 329112.0 29553.4 29952.6 30110.8 34126.3 35809.9 41366.0 41991.4 42089.6 43992.0 44047.3 28065.6 28069.4 28790.4 29058.1 30566.9 29459.0 29682.7 30000.2 30012.5 30161.7 34169.1 36024.5 36043.0 36049.3 36135.1 26400.1 29370.0 29408.0 30826.5 30896.1 22085.6 23815.0 23949.0 24095.0 24186.3 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:38210.37 Median:21766.06 stddev:94799.73 (435.5%) stddev-sans-first:95023.33 13:34:23 INFO - PID 1643 | Values: 478.6 504.6 518.3 520.9 537.4 21568.9 21689.2 21975.6 22341.9 22350.2 23731.8 23776.5 24004.9 24005.8 24032.0 6242.2 6428.6 6429.6 6445.4 6456.2 6320.0 6383.6 6432.5 6436.4 6436.9 5747.9 6283.1 6301.5 6302.8 6314.4 6318.4 6335.8 6359.1 6368.3 6371.8 5859.9 5863.1 5863.9 5869.6 5880.6 28572.3 28935.9 29687.1 29958.7 30134.0 2097.8 2317.5 2340.9 2345.0 2348.0 18742.9 19086.1 19122.8 19275.5 19293.8 2190.8 2209.3 2258.4 2265.3 2286.6 172397.0 181187.0 181392.0 181681.0 195655.0 13890.3 14226.3 15037.2 15073.9 15384.2 20057.0 20074.9 20110.3 20139.0 20208.4 10271.4 10289.5 10343.7 10347.8 10409.6 9733.4 9814.4 9826.3 9826.9 9828.5 7216.9 7395.5 7988.2 8863.4 8945.7 2485.5 2496.8 2497.4 2498.6 2499.5 876.4 931.3 964.9 976.2 1006.7 20932.4 21013.4 21186.6 21737.6 21794.6 979.2 985.7 1004.5 1035.5 1050.4 10740.8 11013.0 11026.3 11370.1 11484.5 528175.0 541112.0 549242.0 566013.0 567729.0 139778.0 143795.3 143993.0 144078.0 148083.5 1174.8 1190.7 1205.6 1213.6 1220.0 17597.2 19104.5 19117.9 19352.0 19604.8 60914.8 61164.0 61207.4 63212.0 66722.7 82503.6 83802.0 86332.0 86493.0 87167.7 11489.4 11908.6 12163.3 12198.8 12223.0 18468.4 18531.0 18548.9 18559.3 18703.0 14807.6 14837.7 14905.6 14975.3 15058.5 12210.3 12296.0 12556.7 13167.7 13455.7 14687.4 14925.3 15013.8 15125.9 15132.3 11781.1 11880.6 12004.8 12015.2 12086.6 14680.2 14805.7 14821.3 14882.2 14901.4 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:8426.68 Median:2805.46 stddev:5610.44 (200.0%) stddev-sans-first:5601.51 13:34:23 INFO - PID 1643 | Values: 1425.2 1449.8 1471.6 1479.1 1484.6 10981.3 12418.9 12810.8 12922.9 12945.4 13326.7 13740.6 14208.9 14217.4 14245.8 2743.9 2810.0 2817.2 2819.6 2820.9 2779.1 2814.6 2822.4 2823.8 2826.5 2610.7 2617.8 2682.7 2778.5 2823.1 2836.4 2853.8 2856.0 2860.6 2863.4 2776.8 2785.9 2796.5 2803.6 2807.3 13436.2 13752.7 13760.6 13790.0 13797.3 7572.8 7860.0 7863.6 7867.0 7880.2 4753.9 4895.9 4923.8 4994.8 5039.6 1235.0 1382.9 1385.8 1387.6 1408.7 20469.5 20481.0 20602.1 20650.7 20651.9 5676.0 6645.8 6713.4 6845.4 6846.1 14281.0 14534.4 14571.9 14572.9 14584.1 7408.9 7525.7 7563.4 7567.5 7579.4 6826.5 6940.8 6969.8 7011.5 7037.8 4572.4 4734.5 4842.0 5333.4 5361.3 1537.3 1545.0 1545.0 1560.5 1561.5 814.2 829.2 843.3 847.3 856.6 8021.6 8174.3 8290.6 8473.2 8474.4 862.6 970.4 1010.6 1010.9 1019.1 4138.8 4787.0 5015.9 5298.3 5362.4 18279.6 18781.2 18841.6 18860.4 18920.7 18183.8 18547.5 18854.0 18932.6 18962.3 9349.8 9471.3 9503.4 9516.6 9524.4 6682.9 7703.4 7851.5 8042.6 8219.2 16553.3 17282.2 17295.5 17310.4 17438.0 16434.5 17082.2 17620.8 17833.6 17923.9 10700.7 10829.9 10924.9 10956.3 10979.0 12335.6 12427.8 12580.3 12660.4 12673.0 10101.4 10123.9 10275.5 10433.6 10497.2 9837.5 9905.7 10165.7 10214.9 10283.7 9212.7 9429.0 9442.4 9444.8 9459.1 6584.4 6701.9 6796.4 7024.9 7032.3 6649.7 6848.2 6935.8 7133.4 7197.4 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5610.32 Median:6233.47 stddev:1688.86 (27.1%) stddev-sans-first:1647.69 13:34:23 INFO - PID 1643 | Values: 399.5 433.5 458.9 469.8 481.3 6249.3 6480.3 6539.6 6561.0 6618.0 4831.7 6298.1 6372.1 6380.3 6438.8 4218.3 5002.7 5039.8 5042.7 5056.6 4518.6 4672.9 5018.3 5043.9 5047.1 4561.3 4954.4 4972.0 4992.8 5004.2 4567.6 4734.0 4761.0 4768.0 4774.7 4201.0 4201.5 4207.9 4210.0 4214.6 5952.7 6227.8 6242.1 6257.9 6292.6 5241.0 5691.1 5705.5 5711.2 5754.1 6327.7 6939.2 6956.3 7026.9 7300.4 4221.5 4260.3 4719.1 4823.6 4872.8 6066.7 6453.3 7088.8 7289.8 7385.4 4361.3 4949.7 4952.4 4955.3 4958.5 5012.2 5032.6 5045.2 5049.4 5061.4 5691.8 5708.7 5711.3 5744.2 5775.9 5550.8 5815.0 6980.6 6987.5 7013.6 1713.8 1730.5 1746.0 1898.0 1904.8 1067.2 1333.7 1364.6 1369.2 1411.0 4858.8 5180.4 5447.0 5480.3 6371.1 6558.3 6579.2 7241.0 7276.0 7297.9 5701.3 6516.4 6538.8 6570.5 6605.7 3575.8 3735.1 3744.8 3755.5 3759.6 6206.9 6684.9 6831.3 7363.6 7423.0 6849.4 7368.5 7393.7 7415.2 7415.7 6444.4 7363.6 7372.6 7402.3 7421.4 6540.9 7176.7 7187.5 7189.4 7236.3 6165.4 6239.1 7006.2 7049.2 7086.9 6349.8 6438.6 7346.9 7375.0 7388.5 4365.4 4604.3 4908.6 4913.8 4941.9 6213.8 6302.5 7137.0 7146.6 7163.0 6781.4 6877.6 6920.8 6939.2 6957.5 6054.6 6896.2 6905.3 6934.2 6934.8 6729.8 6735.9 6743.0 6753.9 6762.9 6861.5 6920.6 6936.3 6937.6 6980.8 5663.3 6691.4 6700.7 6709.9 6802.1 13:34:23 INFO - PID 1643 | 13:34:23 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:26396.75 Median:18162.14 stddev:41865.33 (230.5%) stddev-sans-first:41938.48 13:34:23 INFO - PID 1643 | Values: 748.3 804.4 822.0 839.3 840.1 16763.5 17248.3 17257.9 17277.2 17293.4 17222.2 18446.6 18511.4 18511.9 18559.0 951.4 953.7 965.8 967.9 969.5 981.2 982.3 982.7 984.5 989.7 967.8 1020.9 1031.0 1035.6 1036.7 1007.5 1010.7 1016.1 1023.1 1024.5 749.2 750.7 750.8 751.6 752.5 17208.7 17290.0 17383.0 17452.7 17490.2 17520.0 17872.8 18107.9 18192.9 18248.5 14858.9 14988.0 15034.7 15047.1 15058.8 3163.1 3474.5 3589.9 3594.9 3609.9 150214.0 152327.0 155765.0 156607.2 157788.0 11515.8 12736.9 12956.6 13067.3 13081.6 22593.3 23333.8 24618.3 25044.6 25600.2 7895.3 7981.9 8007.3 8010.7 8038.6 4082.4 4156.2 4165.5 4191.3 4193.7 4748.5 4809.3 4812.9 4814.6 4817.4 850.2 856.6 918.6 959.5 977.2 573.7 591.7 638.5 661.3 672.0 10158.7 11055.8 11299.0 11345.3 12918.3 627.4 630.4 644.8 656.4 657.6 4759.9 5581.2 5626.6 5652.9 5802.3 149728.0 154022.0 154956.5 156133.4 159797.4 127582.4 132686.0 133641.0 134080.3 139645.0 96743.5 101048.5 107618.9 107804.5 116989.4 11611.7 12103.2 12346.8 12379.0 12691.2 43828.8 46795.9 47440.0 48288.0 49046.8 62146.7 62280.4 65335.3 65638.3 66467.0 14390.6 14485.9 14545.2 14565.6 14643.6 18151.5 18257.8 18488.8 19129.1 19472.7 15330.6 15935.0 16493.8 18172.8 18334.4 15569.2 16685.4 17776.0 17930.5 18079.3 16703.6 16856.7 17019.1 17080.1 17583.5 11345.0 11522.5 11907.5 12070.8 12117.2 12110.8 13358.4 14282.6 14502.4 14559.9 13:34:23 INFO - PID 1643 | -------- Summary: end -------- 13:34:23 INFO - PID 1643 | 13:34:23 INFO - TEST-INFO | 1643: exit 0 13:34:23 INFO - TEST-OK | dromaeo_css | took 1145703ms 13:34:23 INFO - TEST-START | kraken 13:34:23 INFO - operating with platform_type : mac_ 13:34:23 INFO - Initialising browser for kraken test... 13:34:23 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpY_Corp/profile 13:34:23 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 13:34:57 INFO - TEST-INFO | 1655: killed by SIGTERM 13:34:57 INFO - Browser initialized. 13:34:57 INFO - Running cycle 1/1 for kraken test... 13:34:57 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/tmpY_Corp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:34:58 INFO - PID 1659 | 2017-06-16 13:34:58.864 plugin-container[1660:10711] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 13:34:58 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:34:58 INFO - PID 1659 | 2017-06-16 13:34:58.865 plugin-container[1660:10711] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9453, name = 'com.apple.CFPasteboardClient' 13:34:58 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:34:58 INFO - PID 1659 | 2017-06-16 13:34:58.865 plugin-container[1660:10711] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:34:59 INFO - PID 1659 | 2017-06-16 13:34:59.668 plugin-container[1661:10756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 13:34:59 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:34:59 INFO - PID 1659 | 2017-06-16 13:34:59.668 plugin-container[1661:10756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 13:34:59 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:34:59 INFO - PID 1659 | 2017-06-16 13:34:59.668 plugin-container[1661:10756] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:35:09 INFO - PID 1659 | RSS: Main: 214163456 13:35:09 INFO - PID 1659 | 13:35:11 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 13:35:12 INFO - PID 1659 | RSS: Main: 228732928 13:35:12 INFO - PID 1659 | 13:35:15 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 13:35:15 INFO - PID 1659 | RSS: Main: 238186496 13:35:15 INFO - PID 1659 | 13:35:19 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 13:35:19 INFO - PID 1659 | RSS: Main: 241790976 13:35:19 INFO - PID 1659 | 13:35:21 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 13:35:22 INFO - PID 1659 | RSS: Main: 242262016 13:35:22 INFO - PID 1659 | 13:35:24 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:35:25 INFO - PID 1659 | RSS: Main: 243851264 13:35:25 INFO - PID 1659 | 13:35:27 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 13:35:28 INFO - PID 1659 | RSS: Main: 247971840 13:35:28 INFO - PID 1659 | 13:35:31 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 13:35:32 INFO - PID 1659 | RSS: Main: 248586240 13:35:32 INFO - PID 1659 | 13:35:35 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 13:35:35 INFO - PID 1659 | RSS: Main: 248184832 13:35:35 INFO - PID 1659 | 13:35:37 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:35:37 INFO - PID 1659 | RSS: Main: 247631872 13:35:37 INFO - PID 1659 | 13:35:39 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:35:40 INFO - PID 1659 | RSS: Main: 247701504 13:35:40 INFO - PID 1659 | 13:35:42 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:35:43 INFO - PID 1659 | RSS: Main: 247595008 13:35:43 INFO - PID 1659 | 13:35:45 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:35:46 INFO - PID 1659 | RSS: Main: 247648256 13:35:46 INFO - PID 1659 | 13:35:49 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:35:50 INFO - PID 1659 | RSS: Main: 247889920 13:35:50 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 13:35:52 INFO - PID 1659 | RSS: Main: 249659392 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | __start_tp_report 13:35:52 INFO - PID 1659 | _x_x_mozilla_page_load 13:35:52 INFO - PID 1659 | _x_x_mozilla_page_load_details 13:35:52 INFO - PID 1659 | |i|pagename|runs| 13:35:52 INFO - PID 1659 | |0;ai-astar;103;117;102;117;118;113;114;103;113;111 13:35:52 INFO - PID 1659 | |1;audio-beat-detection;136;116;168;160;140;163;140;145;143;109 13:35:52 INFO - PID 1659 | |2;audio-dft;203;199;234;254;200;205;198;235;290;190 13:35:52 INFO - PID 1659 | |3;audio-fft;70;84;112;181;86;58;60;63;127;79 13:35:52 INFO - PID 1659 | |4;audio-oscillator;69;79;109;70;71;81;108;69;80;95 13:35:52 INFO - PID 1659 | |5;imaging-gaussian-blur;89;90;88;88;89;89;88;90;89;95 13:35:52 INFO - PID 1659 | |6;imaging-darkroom;96;97;96;97;96;96;96;98;96;151 13:35:52 INFO - PID 1659 | |7;imaging-desaturate;90;93;91;90;94;89;89;92;89;87 13:35:52 INFO - PID 1659 | |8;json-parse-financial;44;41;74;46;45;52;63;72;72;72 13:35:52 INFO - PID 1659 | |9;json-stringify-tinderbox;55;58;75;81;75;76;57;56;56;69 13:35:52 INFO - PID 1659 | |10;stanford-crypto-aes;78;81;79;80;81;80;80;79;78;93 13:35:52 INFO - PID 1659 | |11;stanford-crypto-ccm;135;133;142;138;126;149;157;154;144;178 13:35:52 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;210;222;174;175;179;175;212;180;179;163 13:35:52 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;67;69;91;85;81;66;64;66;67;62 13:35:52 INFO - PID 1659 | __end_tp_report 13:35:52 INFO - PID 1659 | __start_cc_report 13:35:52 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1037 13:35:52 INFO - PID 1659 | __end_cc_report 13:35:52 INFO - PID 1659 | __startTimestamp1497645352265__endTimestamp 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | ------- Summary: start ------- 13:35:52 INFO - PID 1659 | Number of tests: 14 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:111.10 Median:113.50 stddev:6.21 (5.5%) stddev-sans-first:5.85 13:35:52 INFO - PID 1659 | Values: 103.0 117.0 102.0 117.0 118.0 113.0 114.0 103.0 113.0 111.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:144.00 stddev:19.03 (13.2%) stddev-sans-first:20.06 13:35:52 INFO - PID 1659 | Values: 136.0 116.0 168.0 160.0 140.0 163.0 140.0 145.0 143.0 109.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:220.80 Median:219.50 stddev:31.99 (14.6%) stddev-sans-first:33.27 13:35:52 INFO - PID 1659 | Values: 203.0 199.0 234.0 254.0 200.0 205.0 198.0 235.0 290.0 190.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:92.00 Median:66.50 stddev:38.47 (57.9%) stddev-sans-first:39.97 13:35:52 INFO - PID 1659 | Values: 70.0 84.0 112.0 181.0 86.0 58.0 60.0 63.0 127.0 79.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:75.00 stddev:15.56 (20.7%) stddev-sans-first:15.64 13:35:52 INFO - PID 1659 | Values: 69.0 79.0 109.0 70.0 71.0 81.0 108.0 69.0 80.0 95.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:89.00 stddev:2.07 (2.3%) stddev-sans-first:2.19 13:35:52 INFO - PID 1659 | Values: 89.0 90.0 88.0 88.0 89.0 89.0 88.0 90.0 89.0 95.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:96.00 stddev:17.27 (18.0%) stddev-sans-first:18.18 13:35:52 INFO - PID 1659 | Values: 96.0 97.0 96.0 97.0 96.0 96.0 96.0 98.0 96.0 151.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:90.50 stddev:2.12 (2.3%) stddev-sans-first:2.24 13:35:52 INFO - PID 1659 | Values: 90.0 93.0 91.0 90.0 94.0 89.0 89.0 92.0 89.0 87.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:67.50 stddev:13.76 (20.4%) stddev-sans-first:13.61 13:35:52 INFO - PID 1659 | Values: 44.0 41.0 74.0 46.0 45.0 52.0 63.0 72.0 72.0 72.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:72.00 stddev:10.34 (14.4%) stddev-sans-first:10.20 13:35:52 INFO - PID 1659 | Values: 55.0 58.0 75.0 81.0 75.0 76.0 57.0 56.0 56.0 69.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:80.00 stddev:4.38 (5.5%) stddev-sans-first:4.52 13:35:52 INFO - PID 1659 | Values: 78.0 81.0 79.0 80.0 81.0 80.0 80.0 79.0 78.0 93.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:146.50 stddev:14.87 (10.2%) stddev-sans-first:15.27 13:35:52 INFO - PID 1659 | Values: 135.0 133.0 142.0 138.0 126.0 149.0 157.0 154.0 144.0 178.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.90 Median:179.50 stddev:19.97 (11.1%) stddev-sans-first:19.35 13:35:52 INFO - PID 1659 | Values: 210.0 222.0 174.0 175.0 179.0 175.0 212.0 180.0 179.0 163.0 13:35:52 INFO - PID 1659 | 13:35:52 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:68.00 stddev:10.03 (14.8%) stddev-sans-first:10.49 13:35:52 INFO - PID 1659 | Values: 67.0 69.0 91.0 85.0 81.0 66.0 64.0 66.0 67.0 62.0 13:35:52 INFO - PID 1659 | -------- Summary: end -------- 13:35:52 INFO - PID 1659 | 13:35:53 INFO - TEST-INFO | 1659: exit 0 13:35:53 INFO - TEST-OK | kraken | took 89230ms 13:35:53 INFO - SUITE-END | took 1234s 13:35:53 INFO - Completed test suite (00:20:35) 13:35:53 INFO - javascript benchmark 13:35:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8402.39578657999, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3535.4166562517185, 3555.71554275658, 3569.4467357559574, 3610.512580801593, 3622.4928510008585, 5502.80740174094, 6136.978641397139, 6222.906656400152, 6260.906086408701, 6340.58786178898, 6292.0, 6307.400796924297, 6330.689298406071, 6340.365963403658, 6350.110984462179, 5467.644603100795, 5530.834074977757, 5553.805616803413, 5568.081266591008, 5579.832605021836, 5549.667019978814, 5565.193047008182, 5574.832755017335, 5580.330360356763, 5594.132909399029, 5417.566594672417, 5440.401555828856, 5446.319210098738, 5464.98897703926, 5475.397706252349, 4639.353029674451, 5530.778768849241, 5538.833834984957, 5583.8883222335735, 5600.0759874620635, 5436.0, 5446.945530544684, 5468.316460442445, 5506.697131657757, 5562.16567514876, 6292.5280603954425, 6293.496520278412, 6296.68516574174, 6328.145700330442, 6328.367163283681, 6610.537262391635, 6722.0, 6842.726290948356, 6847.691853866588, 6868.381845633917, 6810.829729256809, 6902.171739391305, 6940.6529673516125, 6949.965250173767, 6951.93048069523, 6061.636301821904, 6426.74293028283, 6481.578697384655, 6486.772962946275, 6488.351164883474, 6875.346842050098, 6975.79072627822, 6989.231184569693, 6997.895031574429, 7154.248803875622, 6564.638944857983, 6602.966985165043, 6609.372109649672, 6630.303818099127, 6719.496037797087, 6885.51801373899, 6889.483288753364, 6911.308869113149, 6915.273896240923, 6918.446524278046, 6756.358145976124, 6778.22050464193, 6779.186497620317, 6784.050232967388, 6794.082798822095, 6564.9671751640935, 6670.833229169212, 6705.865882682319, 6781.423578995742, 6826.078479405217, 5474.315710536183, 5476.91784623231, 5493.10869077588, 5498.45015498455, 5537.086380747131, 4849.2726091085915, 4870.780814863339, 4924.901501969941, 4973.651844370859, 4982.800687972441, 5718.370979192286, 6501.1548498694865, 6515.15303010615, 6515.348465153446, 6542.345765423514, 6989.475789315821, 7000.544964577286, 7006.4745144114395, 7028.4025857801635, 7042.366187043191, 5672.631278966854, 6064.636121832616, 6265.530085243534, 6307.842303942346, 6319.526035547352, 5180.6891586504935, 5636.802711905064, 6055.666938318434, 6110.755569777248, 6160.661163636043, 7060.293970602898, 7093.503454758117, 7099.858002840017, 7127.2516385778745, 7128.964355178191, 6934.757283495053, 6938.375546200866, 6971.8605627886145, 6989.091418115612, 7021.719131234693, 7077.752278670222, 7086.50394472382, 7107.040549525748, 7118.0, 7120.1099862517185, 6621.139251897415, 6685.69914353865, 6706.966465167643, 6734.764283250259, 6799.5920244785475, 6955.269696681776, 6957.026016357613, 6961.338672826073, 6986.9650651746415, 7001.789946301619, 5617.606767526234, 6738.191417029988, 6739.93260067393, 6745.325467453215, 6766.830829229309, 6511.804645860631, 6523.869522609616, 6605.504587156079, 6726.596404215763, 6751.459883209233, 6430.324815894263, 6438.195225596801, 6444.258910225384, 6446.323136070645, 6550.475961922939, 6710.261871194067, 6734.158230221223, 6760.695768690519, 6776.15298087739, 6782.525223234326, 6609.834754131185, 6627.138471998609, 6633.104530888461, 6638.402543771083, 6638.83402914931], "value": 6231.42667918998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.1969636436278, 782.8629989751792, 800.7837883771379, 811.8335741173061, 824.0441088337528, 36685.08287292819, 37395.62604373959, 37556.624433755656, 37556.81221593888, 37557.43663845045, 36660.63339366605, 36985.81507092468, 36986.8150659247, 37003.814980925126, 37056.25887482249, 7363.5213711108745, 7497.962510187442, 7501.324880760731, 7503.624818759067, 7526.7741967740785, 7390.519616224955, 7497.2877576630135, 7502.249775022508, 7508.587027713474, 7521.5111017783665, 7462.664180111881, 7470.551766894004, 7499.400047996147, 7513.173550909396, 7514.924850751478, 7448.441366897478, 7470.47706660534, 7486.925130748705, 7875.0, 8092.554909479976, 7975.561344126071, 8046.919530804676, 8051.838963220703, 8053.275205231499, 8076.434649574532, 34727.9581612552, 34846.651533484845, 35227.82386088053, 35276.823615882015, 35293.64706352955, 3579.1231148368815, 3614.3855544557487, 3615.8915232542804, 3617.1680513482047, 3624.0577449862963, 28680.709368078486, 28709.28226794347, 28833.279168020967, 28855.711442885513, 28860.0, 3002.8198308101582, 3046.512557990711, 3090.5673205751214, 3091.165385345944, 3106.4408406486823, 209028.0, 250710.0, 251877.0, 302400.0, 306458.46770766005, 37827.10864456766, 46622.53377466182, 46703.76648116806, 46729.29906051412, 46796.5320346799, 49427.0, 49494.0, 49838.750806245735, 49952.0, 50006.24990625143, 15122.54632361031, 15140.772888406682, 15282.847171528143, 15308.770368444482, 15310.770338444932, 12551.12142150059, 12553.560625378068, 12581.811272830915, 12614.684632884251, 12635.557755478514, 13354.332283385791, 13358.866411335957, 13383.866161338457, 13427.12723672958, 13475.528356507672, 5953.374895635982, 5974.3428222895445, 6025.0359942409, 6029.78895738647, 6034.9396506035255, 944.6929747831982, 1005.7737009173023, 1010.5907107621425, 1011.9949400252951, 1072.495926914349, 22767.7723222769, 22922.31233063011, 23313.0, 23457.41356466102, 24462.633060504108, 1009.8485227215976, 1038.4651904269308, 1051.4847724614883, 1094.5020015892908, 1103.9172062095374, 12935.835774780315, 13508.459661613622, 16187.271572779253, 16543.83456165447, 16779.328826846788, 365843.0, 366219.0, 368050.0, 368457.0, 369202.0, 55567.44432555704, 55630.443695563335, 55759.72120139373, 56118.71940640352, 56511.43488565062, 1483.5845963130341, 1523.1546491697216, 1545.806774153231, 1547.8142622885325, 1549.1634517360724, 27952.860235698692, 29232.83068677229, 29521.81912723553, 29778.702212977594, 29987.850060749555, 50305.49694503008, 50719.49280507147, 50749.0, 50763.238551422495, 50958.0, 326325.0, 326837.0, 327216.0, 328021.0, 329112.0, 29553.408931820813, 29952.550711738906, 30110.849445752632, 34126.31747364989, 35809.925702228975, 41366.0, 41991.370129447474, 42089.57910420857, 43992.0, 44047.339289910036, 28065.57901631518, 28069.43861122807, 28790.42419151647, 29058.128256152348, 30566.93015744438, 29459.0, 29682.703172967995, 30000.24999375033, 30012.549811753277, 30161.698383016766, 34169.145771355914, 36024.459633105, 36043.0, 36049.27901442009, 36135.10919126516, 26400.143195088975, 29370.0, 29408.0, 30826.53760193554, 30896.073117806503, 22085.55828883381, 23815.047398104576, 23949.04203831827, 24095.0, 24186.274411767674], "value": 17424.43289197076, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.55972505295125, 504.57363527819, 518.3158231134898, 520.9086962812905, 537.3846945247694, 21568.92155392228, 21689.24087656932, 21975.560488790215, 22341.888290558563, 22350.21774237902, 23731.76268237317, 23776.52446951064, 24004.91977860996, 24005.75994240057, 24032.0, 6242.219722534684, 6428.646424446653, 6429.614223146603, 6445.387688169616, 6456.22525296965, 6319.968400157993, 6383.585066970632, 6432.5497215194955, 6436.35636436355, 6436.903446448284, 5747.942520574804, 6283.057541368808, 6301.527385446087, 6302.779402720906, 6314.431701146896, 6318.368163183668, 6335.778247761329, 6359.109724638554, 6368.2994870564435, 6371.808845734589, 5859.941400585983, 5863.120531920204, 5863.941360586382, 5869.647821130746, 5880.558958078119, 28572.285692857637, 28935.855320723473, 29687.109386718217, 29958.700412996026, 30134.0, 2097.811196992278, 2317.5480781247666, 2340.9297721068397, 2344.991653588956, 2348.0373047050625, 18742.906285468485, 19086.141123649468, 19122.80877191238, 19275.51811204717, 19293.807061929343, 2190.8028277454946, 2209.2709405896017, 2258.4128126687015, 2265.286434773058, 2286.565552545011, 172397.0, 181187.0, 181392.0, 181681.0, 195655.02172489188, 13890.305484725723, 14226.297003569496, 15037.248137593077, 15073.924630376998, 15384.15387153695, 20057.0, 20074.899625501777, 20110.296139635335, 20138.993050347424, 20208.393748187576, 10271.383716977005, 10289.53697083633, 10343.689689309333, 10347.793044139074, 10409.5836166554, 9733.415995040321, 9814.362066465657, 9826.312158149003, 9826.901730982741, 9828.508574571242, 7216.8917466238045, 7395.51929124616, 7988.201179881965, 8863.423877447945, 8945.731628051039, 2485.4780496096023, 2496.7752902238885, 2497.400623850263, 2498.6252062190815, 2499.487605040962, 876.3602570123845, 931.2596485793811, 964.9276304277207, 976.2385339435252, 1006.7147575004699, 20932.372028838854, 21013.369598912057, 21186.576268474546, 21737.565248694937, 21794.564108717736, 979.1677074486629, 985.6698006168014, 1004.5133202859809, 1035.5081336365317, 1050.4432650695144, 10740.764812046715, 11013.0, 11026.338419694845, 11370.147238957112, 11484.483198256099, 528175.0, 541112.0, 549242.0, 566013.0, 567729.0, 139777.99999999796, 143795.2810235942, 143993.0, 144078.0, 148083.5191648091, 1174.7650469905882, 1190.7082764722697, 1205.6202296276645, 1213.605578187075, 1219.9512019519298, 17597.208125634632, 19104.522386940436, 19117.90441047786, 19352.0, 19604.803951960297, 60914.78170436655, 61164.0, 61207.387926120166, 63212.0, 66722.66554668783, 82503.58748206221, 83802.0, 86332.0, 86493.0, 87167.69248461405, 11489.368084755419, 11908.583199587973, 12163.2702037878, 12198.756024879276, 12222.961048310994, 18468.353607623667, 18531.0, 18548.907255463637, 18559.25762969466, 18702.97133657662, 14807.555773326818, 14837.703245935238, 14905.552833415015, 14975.251237438302, 15058.472953446577, 12210.26738395699, 12296.0, 12556.686082848002, 13167.736645267232, 13455.654434556465, 14687.41250349974, 14925.328360223599, 15013.849861501245, 15125.924370378078, 15132.319045643193, 11781.116416268986, 11880.643580692593, 12004.819927701266, 12015.158938874194, 12086.637400877988, 14680.192589407685, 14805.703885922436, 14821.25893705332, 14882.18148001827, 14901.403943842124], "value": 11587.712150799802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.1805212003098, 1449.8115245018146, 1471.6173794813346, 1479.1273148842183, 1484.6140003599069, 10981.286216395929, 12418.937905310471, 12810.80783788244, 12922.870771292284, 12945.352732363392, 13326.733465330688, 13740.58778236652, 14208.857911420882, 14217.431302747878, 14245.78631320531, 2743.917682469524, 2810.0445848411537, 2817.2252630526564, 2819.647544056993, 2820.8589570521494, 2779.069011881022, 2814.6481689788775, 2822.421403612265, 2823.8446885421295, 2826.5053615617185, 2610.6946526736633, 2617.7958139255966, 2682.7183145769704, 2778.527650299444, 2823.110720123165, 2836.3901761121356, 2853.843038632864, 2855.971440285591, 2860.599516067752, 2863.3700585871075, 2776.7778577713734, 2785.9164225073077, 2796.4546913351905, 2803.6074949506883, 2807.3122085089285, 13436.193828370231, 13752.724945501133, 13760.587182384545, 13790.0, 13797.310134493235, 7572.848543029164, 7860.017497812773, 7863.56750378729, 7866.960665196694, 7880.211978802131, 4753.928691069636, 4895.926561101586, 4923.75381230937, 4994.750262486861, 5039.571636410909, 1234.993825030878, 1382.9377678004514, 1385.8336999560117, 1387.5906607550796, 1408.6689627937528, 20469.488262793402, 20480.97595120238, 20602.072906719233, 20650.690239646417, 20651.896740516502, 5675.971620141955, 6645.767398141042, 6713.362230588086, 6845.418139458102, 6846.110005699227, 14281.0, 14534.418623255162, 14571.85428145705, 14572.927135364467, 14584.124952502883, 7408.85182296351, 7525.661345239368, 7563.432742544332, 7567.508111972704, 7579.431542634325, 6826.4538836892925, 6940.826479337956, 6969.756058537927, 7011.474139439562, 7037.8240543985785, 4572.4055872736335, 4734.502877197913, 4841.983183531498, 5333.439988801198, 5361.276227709287, 1537.3312609015113, 1544.9845501544842, 1544.9915110356492, 1560.492839827038, 1561.4691005058107, 814.1858141858141, 829.2371018662844, 843.2747836860295, 847.2798121596717, 856.5973992223645, 8021.598920054091, 8174.3051840593025, 8290.590599598083, 8473.195046470575, 8474.449160804528, 862.6376921692904, 970.3886551472668, 1010.6008126789877, 1010.8534262532039, 1019.0980981831133, 4138.813753381105, 4787.0, 5015.949840501621, 5298.258243845865, 5362.356517217959, 18279.63440731178, 18781.15484803187, 18841.62316753657, 18860.434186974413, 18920.71618925717, 18183.818161818213, 18547.536311592048, 18853.963032033094, 18932.62134757297, 18962.336318228794, 9349.766255843659, 9471.289653276031, 9503.382280151767, 9516.571754271074, 9524.380915240627, 6682.899756503559, 7703.38372930153, 7851.450398472052, 8042.638081286239, 8219.219174178443, 16553.337866485206, 17282.22229999628, 17295.481135565955, 17310.394136205174, 17438.0, 16434.506964791075, 17082.231299591298, 17620.823791762432, 17833.643327133643, 17923.92456452565, 10700.678979630624, 10829.945850271013, 10924.945375273392, 10956.28784129029, 10979.0, 12335.629931102081, 12427.75144497123, 12580.30808305552, 12660.366981651065, 12673.0, 10101.449561967325, 10123.898761012295, 10275.486225688835, 10433.582656693647, 10497.2127090467, 9837.508124593885, 9905.702828915144, 10165.745856353651, 10214.927432619765, 10283.742906427398, 9212.723618291462, 9429.0, 9442.38624489406, 9444.763880902758, 9459.10138536838, 6584.407403333723, 6701.865962680621, 6796.388325050571, 7024.894626580503, 7032.26161253061, 6649.700763465559, 6848.212455567674, 6935.791926242221, 7133.429325654039, 7197.424206063607], "value": 6226.03901255879, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.5485101834927, 433.5014733056986, 458.92657174852025, 469.7956388970798, 481.3237401450958, 6249.31257561669, 6480.31956644552, 6539.574927629701, 6561.0, 6617.974213996826, 4831.704940153434, 6298.079085878805, 6372.107904893307, 6380.298167201604, 6438.839029024277, 4218.282891908383, 5002.674826136307, 5039.798408063692, 5042.722650254234, 5056.620753443488, 4518.615917646987, 4672.929906051412, 5018.2974383586325, 5043.899122017558, 5047.116754567936, 4561.29299958507, 4954.430240522332, 4971.950280497203, 4992.775325110379, 5004.199328107504, 4567.611753000999, 4734.0295239476, 4761.0, 4767.952320476786, 4774.665773395864, 4200.957990420118, 4201.516825565038, 4207.936880946788, 4210.0, 4214.641755450791, 5952.702364881782, 6227.844303892393, 6242.094896240065, 6257.871064467766, 6292.622442653455, 5240.973795131038, 5691.146328050784, 5705.486506214419, 5711.171880077365, 5754.050581654022, 6327.715252813635, 6939.23668396481, 6956.339147780953, 7026.910828821541, 7300.4159667226495, 4221.472315960505, 4260.275753121946, 4719.07977944301, 4823.565879070866, 4872.756362181912, 6066.6663333516635, 6453.257875344349, 7088.794904866185, 7289.817754556126, 7385.4460915431555, 4361.258586040381, 4949.663590830455, 4952.405711314594, 4955.281484184845, 4958.528939750718, 5012.1980483122525, 5032.597392208614, 5045.217991211368, 5049.368828896388, 5061.417936937226, 5691.8292451226525, 5708.71456427177, 5711.343195532567, 5744.224529688522, 5775.8556036099435, 5550.833474995757, 5814.993827129633, 6980.616066116311, 6987.475939304572, 7013.580314791388, 1713.7515060316184, 1730.4981555348809, 1746.0, 1898.003548137239, 1904.7523821903342, 1067.2475904487474, 1333.7199188170398, 1364.597443754095, 1369.2469141971876, 1410.978835317471, 4858.78135483904, 5180.352455943007, 5447.0, 5480.314960629921, 6371.076193951943, 6558.344165583403, 6579.24338701046, 7240.96379518099, 7276.0, 7297.927020729831, 5701.265670985428, 6516.413522782877, 6538.8365290868105, 6570.540062195696, 6605.669716514155, 3575.8212089395424, 3735.14091758897, 3744.7753134811996, 3755.4930084438797, 3759.642833930772, 6206.882761103045, 6684.9331506685285, 6831.316868313228, 7363.631818409058, 7422.958686361294, 6849.4177994871, 7368.484206105629, 7393.704251829974, 7415.221401752846, 7415.703371865065, 6444.355564443518, 7363.558186508827, 7372.594507302043, 7402.33378995893, 7421.369183619345, 6540.869182616416, 7176.7488137914925, 7187.496875218684, 7189.3889019431845, 7236.34872861445, 6165.445109940127, 6239.0953311770445, 7006.229314775473, 7049.2155598537265, 7086.929130708627, 6349.825282322785, 6438.6136831790245, 7346.889796652948, 7375.0, 7388.519746216479, 4365.410669559569, 4604.293543697356, 4908.6073114151495, 4913.778879950482, 4941.888075183198, 6213.819374318865, 6302.5189080565115, 7136.964315178391, 7146.642667866482, 7162.964185179041, 6781.423578995643, 6877.58734475933, 6920.757773477904, 6939.201991771011, 6957.478189135936, 6054.636721796706, 6896.206936202401, 6905.274946130785, 6934.237233904166, 6934.780870121513, 6729.831754206184, 6735.865282694221, 6742.988551717282, 6753.864922701617, 6762.932370676231, 6861.4853885957755, 6920.550164239308, 6936.341047600672, 6937.618430986344, 6980.825479363057, 5663.32040155184, 6691.431228345645, 6700.731970721116, 6709.932900671126, 6802.075107703735], "value": 5112.681369254432, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.3115533708989, 804.3685706720225, 821.9917800821989, 839.3033781960974, 840.0843081041663, 16763.497095087136, 17248.310067597296, 17257.91371043143, 17277.222524986373, 17293.39473118441, 17222.224999875005, 18446.631067378643, 18511.444656660322, 18511.90744046278, 18558.97925614094, 951.3578334624118, 953.6995846308425, 965.8164948659768, 967.9032096790299, 969.4522594733988, 981.2150279776187, 982.2878413150445, 982.7444864335289, 984.4782265399314, 989.6833013435703, 967.8257913575553, 1020.9438480883548, 1031.0153803118005, 1035.6427032673716, 1036.6941752183125, 1007.5113569918584, 1010.706895000449, 1016.1413605503337, 1023.1150055202271, 1024.472396715692, 749.2058418076856, 750.7334896111911, 750.8122969257686, 751.6166754954958, 752.4995877741295, 17208.741868871977, 17290.0, 17383.043932583703, 17452.738208926876, 17490.21294041771, 17520.0, 17872.821271787376, 18107.909460452745, 18192.90842549438, 18248.543786405313, 14858.925705371405, 14988.025778324374, 15034.699306013928, 15047.097174169585, 15058.849411505855, 3163.066895265902, 3474.513568100468, 3589.8743543976097, 3594.856205751767, 3609.855605775766, 150214.0, 152327.0, 155765.0, 156607.2169639156, 157788.0, 11515.790841961641, 12736.872631273569, 12956.611301660965, 13067.281299528617, 13081.607551773463, 22593.322200334016, 23333.76666233316, 24618.261452156465, 25044.624330635055, 25600.231993040237, 7895.289423951872, 7981.8802717958115, 8007.319377852948, 8010.679572817138, 8038.557879316577, 4082.4488694026522, 4156.210320039183, 4165.541790403053, 4191.308434108399, 4193.706440549132, 4748.501407352247, 4809.326694262806, 4812.8796780080775, 4814.638902082358, 4817.373741413594, 850.2475309351269, 856.6145234644434, 918.5774543709969, 959.5442164971582, 977.2328721953232, 573.674811186153, 591.6627522312327, 638.5370606310388, 661.3089321658878, 671.9899201511981, 10158.695239142837, 11055.834162487568, 11299.0, 11345.31928084301, 12918.289494077728, 627.4290395739855, 630.391672036487, 644.8484606117559, 656.3600489522772, 657.6350125680293, 4759.881002974884, 5581.246531718166, 5626.634268772516, 5652.943470565242, 5802.274715660543, 149728.0, 154022.0, 154956.4504354942, 156133.43866561417, 159797.40202598058, 127582.36208818897, 132686.0, 133641.0, 134080.32959835138, 139645.0, 96743.51628241813, 101048.49475752574, 107618.92381076246, 107804.46097769462, 116989.41505292419, 11611.6516504505, 12103.152779305716, 12346.814797778221, 12379.0, 12691.175073620185, 43828.780856095516, 46795.89418476245, 47440.0, 48288.034239314315, 49046.75476622594, 62146.68926655338, 62280.377196227455, 65335.346646534825, 65638.34361656323, 66467.00299495415, 14390.640233994234, 14485.92757036208, 14545.200013999332, 14565.635859103184, 14643.56069317922, 18151.45545633633, 18257.817421826145, 18488.815111848708, 19129.139188736262, 19472.707909381086, 15330.616734581725, 15935.0, 16493.83506164971, 18172.818271817643, 18334.449966501026, 15569.221538923235, 16685.416010439578, 17776.0, 17930.462086137435, 18079.276828926697, 16703.58241043935, 16856.66286674235, 17019.14904254807, 17080.145992700564, 17583.472495825146, 11345.0, 11522.53909843597, 11907.464164112462, 12070.818937715765, 12117.212381195193, 12110.818337725117, 13358.398872050586, 14282.57152285433, 14502.419903203754, 14559.85440145585], "value": 8785.941430643355, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 102.0, 117.0, 118.0, 113.0, 114.0, 103.0, 113.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 116.0, 168.0, 160.0, 140.0, 163.0, 140.0, 145.0, 143.0, 109.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 199.0, 234.0, 254.0, 200.0, 205.0, 198.0, 235.0, 290.0, 190.0], "value": 220.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 84.0, 112.0, 181.0, 86.0, 58.0, 60.0, 63.0, 127.0, 79.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 109.0, 70.0, 71.0, 81.0, 108.0, 69.0, 80.0, 95.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 88.0, 89.0, 89.0, 88.0, 90.0, 89.0, 95.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 97.0, 96.0, 96.0, 96.0, 98.0, 96.0, 151.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 91.0, 90.0, 94.0, 89.0, 89.0, 92.0, 89.0, 87.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 41.0, 74.0, 46.0, 45.0, 52.0, 63.0, 72.0, 72.0, 72.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 75.0, 81.0, 75.0, 76.0, 57.0, 56.0, 56.0, 69.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 79.0, 80.0, 81.0, 80.0, 80.0, 79.0, 78.0, 93.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 133.0, 142.0, 138.0, 126.0, 149.0, 157.0, 154.0, 144.0, 178.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 222.0, 174.0, 175.0, 179.0, 175.0, 212.0, 180.0, 179.0, 163.0], "value": 186.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 91.0, 85.0, 81.0, 66.0, 64.0, 66.0, 67.0, 62.0], "value": 71.8, "unit": "score"}]}]} 13:35:53 INFO - Return code: 0 13:35:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:35:53 INFO - # TBPL SUCCESS # 13:35:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:35:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:35:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:35:53 INFO - 2017-06-16 13:35:53.651 screenresolution[1667:11150] starting screenresolution argv=screenresolution get 13:35:53 INFO - 2017-06-16 13:35:53.653 screenresolution[1667:11150] Display 0: 1600x1200x32@60 13:35:53 INFO - 2017-06-16 13:35:53.659 screenresolution[1668:11153] starting screenresolution argv=screenresolution list 13:35:53 INFO - Available Modes on Display 0 13:35:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:35:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:35:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:35:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:35:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:35:53 INFO - Intel Iris: 13:35:53 INFO - Chipset Model: Intel Iris 13:35:53 INFO - Type: GPU 13:35:53 INFO - Bus: Built-In 13:35:53 INFO - VRAM (Dynamic, Max): 1536 MB 13:35:53 INFO - Vendor: Intel (0x8086) 13:35:53 INFO - Device ID: 0x0a2e 13:35:53 INFO - Revision ID: 0x0009 13:35:53 INFO - Displays: 13:35:53 INFO - Display: 13:35:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:35:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:35:53 INFO - Display Serial Number: 1600x1200 60 13:35:53 INFO - Main Display: Yes 13:35:53 INFO - Mirror: Off 13:35:53 INFO - Online: Yes 13:35:53 INFO - Rotation: Supported 13:35:53 INFO - Return code: 0 13:35:53 INFO - Running post-action listener: _resource_record_post_action 13:35:53 INFO - [mozharness: 2017-06-16 20:35:53.903813Z] Finished run-tests step (success) 13:35:53 INFO - Running post-run listener: _resource_record_post_run 13:35:53 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 47528448; Write bytes: 559035392; Read time: 554; Write time: 1740 13:35:53 INFO - TinderboxPrint: CPU usage
53.0% 13:35:53 INFO - TinderboxPrint: I/O read bytes / time
47,528,448 / 554 13:35:53 INFO - TinderboxPrint: I/O write bytes / time
559,035,392 / 1,740 13:35:53 INFO - TinderboxPrint: CPU idle
2,361.3 (46.9%) 13:35:53 INFO - TinderboxPrint: CPU system
1,014.7 (20.2%) 13:35:53 INFO - TinderboxPrint: CPU user
1,653.6 (32.9%) 13:35:53 INFO - TinderboxPrint: Swap in / out
282,062,848 / 0 13:35:53 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232104448; Write bytes: 228538368; Read time: 16900; Write time: 449 13:35:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:35:53 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 45517312; Write bytes: 311184384; Read time: 526; Write time: 1243 13:35:54 INFO - Running post-run listener: _upload_blobber_files 13:35:54 INFO - Blob upload gear active. 13:35:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:35:54 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:35:54 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:35:54 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:35:54 INFO - (blobuploader) - INFO - Open directory for files ... 13:35:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:35:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:35:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:35:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:35:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:35:56 INFO - (blobuploader) - INFO - Done attempting. 13:35:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:35:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:35:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:35:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:35:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:35:57 INFO - (blobuploader) - INFO - Done attempting. 13:35:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:35:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:35:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:35:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:35:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:35:58 INFO - (blobuploader) - INFO - Done attempting. 13:35:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:35:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:35:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:36:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:36:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:36:00 INFO - (blobuploader) - INFO - Done attempting. 13:36:00 INFO - (blobuploader) - INFO - Iteration through files over. 13:36:00 INFO - Return code: 0 13:36:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:36:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:36:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7b75630d50e1915a04896350b003320b07155374acfd53ce08f4f7bfcc10436455b5094344a26709aa6c5e8c7473c28b119661ebd8ce028c2dbf1f7e77b10fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c235517003eec3b24505310050684f84684fb42e18b5b8560ad2271995154804f21069e812067457b08e1111129228a11ef88fd316fcf4a68415752f329f5d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe184304101e29308fccaa1d8819663f77d389abb1be98c8a4b5da937a325c81b6049aacedef707774bf5b3fbb4e337c4b7b6fa6c630267828dee4175bfc4c4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/775f03f6a820547c417647dbe509f05e1435b092d272089bf78bc6ac858a3bc19f3f53a394b44165218a89b1a757df54c2c85042e1dc420bc856de8bca27a175"} 13:36:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:36:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:36:00 INFO - Contents: 13:36:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7b75630d50e1915a04896350b003320b07155374acfd53ce08f4f7bfcc10436455b5094344a26709aa6c5e8c7473c28b119661ebd8ce028c2dbf1f7e77b10fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c235517003eec3b24505310050684f84684fb42e18b5b8560ad2271995154804f21069e812067457b08e1111129228a11ef88fd316fcf4a68415752f329f5d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe184304101e29308fccaa1d8819663f77d389abb1be98c8a4b5da937a325c81b6049aacedef707774bf5b3fbb4e337c4b7b6fa6c630267828dee4175bfc4c4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/775f03f6a820547c417647dbe509f05e1435b092d272089bf78bc6ac858a3bc19f3f53a394b44165218a89b1a757df54c2c85042e1dc420bc856de8bca27a175"} 13:36:00 INFO - Running post-run listener: copy_logs_to_upload_dir 13:36:00 INFO - Copying logs to upload dir... 13:36:00 INFO - mkdir: /builds/slave/test/build/upload/logs 13:36:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.759315 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-16 13:36:00.554930) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-16 13:36:00.558383) ========= 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.ye2DmR6h3p/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat 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.An2TUFSl8Y/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7b75630d50e1915a04896350b003320b07155374acfd53ce08f4f7bfcc10436455b5094344a26709aa6c5e8c7473c28b119661ebd8ce028c2dbf1f7e77b10fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c235517003eec3b24505310050684f84684fb42e18b5b8560ad2271995154804f21069e812067457b08e1111129228a11ef88fd316fcf4a68415752f329f5d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe184304101e29308fccaa1d8819663f77d389abb1be98c8a4b5da937a325c81b6049aacedef707774bf5b3fbb4e337c4b7b6fa6c630267828dee4175bfc4c4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/775f03f6a820547c417647dbe509f05e1435b092d272089bf78bc6ac858a3bc19f3f53a394b44165218a89b1a757df54c2c85042e1dc420bc856de8bca27a175"} build_url:https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010939 build_url: 'https://queue.taskcluster.net/v1/task/aUHQfW4PR92FXyYrSoVnZg/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7b75630d50e1915a04896350b003320b07155374acfd53ce08f4f7bfcc10436455b5094344a26709aa6c5e8c7473c28b119661ebd8ce028c2dbf1f7e77b10fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c235517003eec3b24505310050684f84684fb42e18b5b8560ad2271995154804f21069e812067457b08e1111129228a11ef88fd316fcf4a68415752f329f5d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fe184304101e29308fccaa1d8819663f77d389abb1be98c8a4b5da937a325c81b6049aacedef707774bf5b3fbb4e337c4b7b6fa6c630267828dee4175bfc4c4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/775f03f6a820547c417647dbe509f05e1435b092d272089bf78bc6ac858a3bc19f3f53a394b44165218a89b1a757df54c2c85042e1dc420bc856de8bca27a175"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-16 13:36:00.592628) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 13:36:00.593074) ========= 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.ye2DmR6h3p/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat 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.An2TUFSl8Y/Listeners TMPDIR=/var/folders/_q/ws1b_mf156sfthc66fnphd6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn 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.004962 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-16 13:36:00.641630) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-16 13:36:00.641977) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-16 13:36:00.642281) ========= ========= Total master_lag: 0.17 =========