builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1469032165.92 results: success (0) buildid: 20160720075426 builduid: 18ca5a44f31b49c99207025a19ca818d revision: 03269dd72a7259f582977714559f0f4ef19988aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:25.923697) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:25.924206) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:25.924540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:25.947354) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:25.947663) ========= 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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 --2016-07-20 09:29:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.44M=0.002s 2016-07-20 09:29:26 (6.44 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103852 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:26.079088) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:26.079857) ========= 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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.020832 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:26.133803) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 09:29:26.134590) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 03269dd72a7259f582977714559f0f4ef19988aa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 03269dd72a7259f582977714559f0f4ef19988aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RZcQc7b6N9/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.ISRgdGLiPk/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 2016-07-20 09:29:26,212 truncating revision to first 12 chars 2016-07-20 09:29:26,212 Setting DEBUG logging. 2016-07-20 09:29:26,212 attempt 1/10 2016-07-20 09:29:26,212 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/03269dd72a72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-20 09:29:28,142 unpacking tar archive at: mozilla-central-03269dd72a72/testing/mozharness/ program finished with exit code 0 elapsedTime=2.183520 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-20 09:29:28.342223) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:28.342650) ========= script_repo_revision: 03269dd72a7259f582977714559f0f4ef19988aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:28.343171) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:28.343513) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-20 09:29:28.361808) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-07-20 09:29:28.362154) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RZcQc7b6N9/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.ISRgdGLiPk/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:29:28 INFO - MultiFileLogger online at 20160720 09:29:28 in /builds/slave/test 09:29:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:29:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:29:28 INFO - {'append_to_log': False, 09:29:28 INFO - 'base_work_dir': '/builds/slave/test', 09:29:28 INFO - 'blob_upload_branch': 'Firefox', 09:29:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:29:28 INFO - 'branch': 'Firefox', 09:29:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:29:28 INFO - 'config_files': ('talos/mac_config.py',), 09:29:28 INFO - 'default_actions': ('clobber', 09:29:28 INFO - 'read-buildbot-config', 09:29:28 INFO - 'download-and-extract', 09:29:28 INFO - 'populate-webroot', 09:29:28 INFO - 'create-virtualenv', 09:29:28 INFO - 'install', 09:29:28 INFO - 'run-tests'), 09:29:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:29:28 INFO - 'download_minidump_stackwalk': True, 09:29:28 INFO - 'download_symbols': 'ondemand', 09:29:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:29:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:29:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:29:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:29:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:29:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:29:28 INFO - 'installer_path': 'installer.exe', 09:29:28 INFO - 'log_level': 'info', 09:29:28 INFO - 'log_name': 'talos', 09:29:28 INFO - 'log_to_console': True, 09:29:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:29:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:29:28 INFO - 'opt_config_files': (), 09:29:28 INFO - 'pip_index': False, 09:29:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:28 INFO - 'cmd': ('bash', 09:29:28 INFO - '-c', 09:29:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:28 INFO - 'enabled': True, 09:29:28 INFO - 'halt_on_failure': False, 09:29:28 INFO - 'name': 'check_screen_resolution'},), 09:29:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:29:28 INFO - 'cmd': ('bash', 09:29:28 INFO - '-c', 09:29:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:29:28 INFO - 'enabled': True, 09:29:28 INFO - 'halt_on_failure': False, 09:29:28 INFO - 'name': 'check_screen_resolution'},), 09:29:28 INFO - 'run_cmd_checks_enabled': True, 09:29:28 INFO - 'sps_profile': False, 09:29:28 INFO - 'sps_profile_interval': 0, 09:29:28 INFO - 'suite': 'dromaeojs-e10s', 09:29:28 INFO - 'system_bits': '32', 09:29:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:29:28 INFO - 'title': 't-yosemite-r7-0052', 09:29:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:29:28 INFO - 'use_talos_json': True, 09:29:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:29:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:29:28 INFO - 'work_dir': 'build'} 09:29:28 INFO - [mozharness: 2016-07-20 16:29:28.552045Z] Running clobber step. 09:29:28 INFO - Running pre-action listener: _resource_record_pre_action 09:29:28 INFO - Running main action method: clobber 09:29:28 INFO - rmtree: /builds/slave/test/build 09:29:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:29:30 INFO - Running post-action listener: _resource_record_post_action 09:29:30 INFO - [mozharness: 2016-07-20 16:29:30.101421Z] Finished clobber step (success) 09:29:30 INFO - [mozharness: 2016-07-20 16:29:30.101556Z] Running read-buildbot-config step. 09:29:30 INFO - Running pre-action listener: _resource_record_pre_action 09:29:30 INFO - Running main action method: read_buildbot_config 09:29:30 INFO - Using buildbot properties: 09:29:30 INFO - { 09:29:30 INFO - "project": "", 09:29:30 INFO - "product": "firefox", 09:29:30 INFO - "script_repo_revision": "production", 09:29:30 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:29:30 INFO - "repository": "", 09:29:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:29:30 INFO - "buildid": "20160720075426", 09:29:30 INFO - "pgo_build": "False", 09:29:30 INFO - "basedir": "/builds/slave/test", 09:29:30 INFO - "buildnumber": 18, 09:29:30 INFO - "slavename": "t-yosemite-r7-0052", 09:29:30 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:29:30 INFO - "platform": "yosemite_r7", 09:29:30 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:29:30 INFO - "branch": "mozilla-central", 09:29:30 INFO - "repo_path": "mozilla-central", 09:29:30 INFO - "revision": "03269dd72a7259f582977714559f0f4ef19988aa", 09:29:30 INFO - "stage_platform": "macosx64", 09:29:30 INFO - "builduid": "18ca5a44f31b49c99207025a19ca818d", 09:29:30 INFO - "slavebuilddir": "test" 09:29:30 INFO - } 09:29:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:29:30 INFO - Running post-action listener: _resource_record_post_action 09:29:30 INFO - [mozharness: 2016-07-20 16:29:30.104984Z] Finished read-buildbot-config step (success) 09:29:30 INFO - [mozharness: 2016-07-20 16:29:30.105121Z] Running download-and-extract step. 09:29:30 INFO - Running pre-action listener: _resource_record_pre_action 09:29:30 INFO - Running main action method: download_and_extract 09:29:30 INFO - mkdir: /builds/slave/test/build/tests 09:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:30 INFO - https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:29:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:29:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:29:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:29:32 INFO - Downloaded 1199 bytes. 09:29:32 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:29:32 INFO - Using the following test package requirements: 09:29:32 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:29:32 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:29:32 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:29:32 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'jsshell-mac.zip'], 09:29:32 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:29:32 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:29:32 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:29:32 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:29:32 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:29:32 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:29:32 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:29:32 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:32 INFO - https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:29:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:29:38 INFO - Downloaded 18842668 bytes. 09:29:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:29:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:29:42 INFO - Return code: 0 09:29:42 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:42 INFO - https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:29:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:29:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:29:44 INFO - Downloaded 18842668 bytes. 09:29:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:29:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:29:48 INFO - Return code: 0 09:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:48 INFO - https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:29:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:29:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:29:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:29:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:29:49 INFO - Downloaded 14403317 bytes. 09:29:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:29:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:29:49 INFO - Return code: 0 09:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:49 INFO - https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:29:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:29:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:29:52 INFO - Downloaded 112927984 bytes. 09:29:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:52 INFO - mkdir: /builds/slave/test/properties 09:29:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:52 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:52 INFO - Contents: 09:29:52 INFO - build_url:https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:29:53 INFO - Running post-action listener: _resource_record_post_action 09:29:53 INFO - Running post-action listener: set_extra_try_arguments 09:29:53 INFO - [mozharness: 2016-07-20 16:29:53.685957Z] Finished download-and-extract step (success) 09:29:53 INFO - [mozharness: 2016-07-20 16:29:53.686111Z] Running populate-webroot step. 09:29:53 INFO - Running pre-action listener: _resource_record_pre_action 09:29:53 INFO - Running main action method: populate_webroot 09:29:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:53 INFO - '--tppagecycles', 09:29:53 INFO - '7'], 09:29:53 INFO - 'tests': ['tsvgm']}}, 09:29:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['damp', 'tps']}, 09:29:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'tests': ['damp', 'tps']}, 09:29:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['dromaeo_dom']}, 09:29:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['basic_compositor_video']}, 09:29:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:29:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['a11yr', 09:29:53 INFO - 'ts_paint', 09:29:53 INFO - 'tpaint', 09:29:53 INFO - 'sessionrestore', 09:29:53 INFO - 'sessionrestore_no_auto_restore', 09:29:53 INFO - 'tabpaint']}, 09:29:53 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:53 INFO - 'ts_paint', 09:29:53 INFO - 'tpaint', 09:29:53 INFO - 'sessionrestore', 09:29:53 INFO - 'sessionrestore_no_auto_restore', 09:29:53 INFO - 'tabpaint']}, 09:29:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:53 INFO - 'ts_paint', 09:29:53 INFO - 'tpaint', 09:29:53 INFO - 'sessionrestore', 09:29:53 INFO - 'sessionrestore_no_auto_restore', 09:29:53 INFO - 'tabpaint']}, 09:29:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:53 INFO - 'ts_paint', 09:29:53 INFO - 'tpaint', 09:29:53 INFO - 'sessionrestore', 09:29:53 INFO - 'sessionrestore_no_auto_restore', 09:29:53 INFO - 'tabpaint']}, 09:29:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['a11yr', 09:29:53 INFO - 'ts_paint', 09:29:53 INFO - 'tpaint', 09:29:53 INFO - 'sessionrestore', 09:29:53 INFO - 'sessionrestore_no_auto_restore', 09:29:53 INFO - 'tabpaint']}, 09:29:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['a11yr', 09:29:53 INFO - 'ts_paint', 09:29:53 INFO - 'tpaint', 09:29:53 INFO - 'sessionrestore', 09:29:53 INFO - 'sessionrestore_no_auto_restore', 09:29:53 INFO - 'tabpaint']}, 09:29:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['tsvgx', 09:29:53 INFO - 'tsvgr_opacity', 09:29:53 INFO - 'tart', 09:29:53 INFO - 'tscrollx', 09:29:53 INFO - 'cart']}, 09:29:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:53 INFO - 'tsvgr_opacity', 09:29:53 INFO - 'tart', 09:29:53 INFO - 'tscrollx', 09:29:53 INFO - 'cart']}, 09:29:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'talos_options': ['--disable-e10s'], 09:29:53 INFO - 'tests': ['tp5o']}, 09:29:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'tests': ['tp5o']}, 09:29:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'talos_options': ['--disable-e10s', 09:29:53 INFO - '--xperf_path', 09:29:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:53 INFO - 'tests': ['tp5n']}, 09:29:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:53 INFO - 'talos_options': ['--xperf_path', 09:29:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:53 INFO - 'tests': ['tp5n']}}, 09:29:53 INFO - 'talos.zip': {'path': '', 09:29:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:53 INFO - Running post-action listener: _resource_record_post_action 09:29:53 INFO - [mozharness: 2016-07-20 16:29:53.705119Z] Finished populate-webroot step (success) 09:29:53 INFO - [mozharness: 2016-07-20 16:29:53.705250Z] Running create-virtualenv step. 09:29:53 INFO - Running pre-action listener: _resource_record_pre_action 09:29:53 INFO - Running main action method: create_virtualenv 09:29:53 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:53 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 09:29:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:53 INFO - Using real prefix '/tools/python27' 09:29:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:54 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:57 INFO - Installing pip.................done. 09:29:57 INFO - Return code: 0 09:29:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:29:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:29:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:29:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:29:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:57 INFO - 'HOME': '/Users/cltbld', 09:29:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:57 INFO - 'LOGNAME': 'cltbld', 09:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:57 INFO - 'MOZ_NO_REMOTE': '1', 09:29:57 INFO - 'NO_EM_RESTART': '1', 09:29:57 INFO - 'PAGER': '/bin/cat', 09:29:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:57 INFO - 'PWD': '/builds/slave/test', 09:29:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:57 INFO - 'SHELL': '/bin/bash', 09:29:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:29:57 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:29:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:57 INFO - 'USER': 'cltbld', 09:29:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:57 INFO - 'XPC_FLAGS': '0x0', 09:29:57 INFO - 'XPC_SERVICE_NAME': '0', 09:29:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:57 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 09:29:57 INFO - Cleaning up... 09:29:57 INFO - Return code: 0 09:29:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:57 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:29:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:29:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:29:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:29:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:29:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:29:57 INFO - 'HOME': '/Users/cltbld', 09:29:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:29:57 INFO - 'LOGNAME': 'cltbld', 09:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:57 INFO - 'MOZ_NO_REMOTE': '1', 09:29:57 INFO - 'NO_EM_RESTART': '1', 09:29:57 INFO - 'PAGER': '/bin/cat', 09:29:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:57 INFO - 'PWD': '/builds/slave/test', 09:29:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:29:57 INFO - 'SHELL': '/bin/bash', 09:29:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:29:57 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:29:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:29:57 INFO - 'USER': 'cltbld', 09:29:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:29:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:57 INFO - 'XPC_FLAGS': '0x0', 09:29:57 INFO - 'XPC_SERVICE_NAME': '0', 09:29:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:57 INFO - Downloading/unpacking psutil>=3.1.1 09:29:57 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 09:29:57 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 09:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:57 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 09:29:57 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 09:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:00 INFO - Installing collected packages: psutil 09:30:00 INFO - Running setup.py install for psutil 09:30:01 INFO - building 'psutil._psutil_osx' extension 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 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 09:30:01 INFO - building 'psutil._psutil_posix' extension 09:30:01 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 09:30:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:30:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:30:01 INFO - ^ 09:30:01 INFO - 1 warning generated. 09:30:01 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 09:30:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:01 INFO - Successfully installed psutil 09:30:01 INFO - Cleaning up... 09:30:01 INFO - Return code: 0 09:30:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:30:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:30:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:30:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:01 INFO - 'HOME': '/Users/cltbld', 09:30:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:01 INFO - 'LOGNAME': 'cltbld', 09:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:01 INFO - 'MOZ_NO_REMOTE': '1', 09:30:01 INFO - 'NO_EM_RESTART': '1', 09:30:01 INFO - 'PAGER': '/bin/cat', 09:30:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:01 INFO - 'PWD': '/builds/slave/test', 09:30:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:01 INFO - 'SHELL': '/bin/bash', 09:30:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:30:01 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:30:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:01 INFO - 'USER': 'cltbld', 09:30:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:01 INFO - 'XPC_FLAGS': '0x0', 09:30:01 INFO - 'XPC_SERVICE_NAME': '0', 09:30:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:30:02 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 09:30:02 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 09:30:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:02 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 09:30:02 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 09:30:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:30:04 INFO - Installing collected packages: mozsystemmonitor 09:30:04 INFO - Running setup.py install for mozsystemmonitor 09:30:04 INFO - Successfully installed mozsystemmonitor 09:30:04 INFO - Cleaning up... 09:30:04 INFO - Return code: 0 09:30:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:30:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:30:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:05 INFO - 'HOME': '/Users/cltbld', 09:30:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:05 INFO - 'LOGNAME': 'cltbld', 09:30:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:05 INFO - 'MOZ_NO_REMOTE': '1', 09:30:05 INFO - 'NO_EM_RESTART': '1', 09:30:05 INFO - 'PAGER': '/bin/cat', 09:30:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:05 INFO - 'PWD': '/builds/slave/test', 09:30:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:05 INFO - 'SHELL': '/bin/bash', 09:30:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:30:05 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:30:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:05 INFO - 'USER': 'cltbld', 09:30:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:05 INFO - 'XPC_FLAGS': '0x0', 09:30:05 INFO - 'XPC_SERVICE_NAME': '0', 09:30:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:05 INFO - Downloading/unpacking jsonschema==2.5.1 09:30:05 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 09:30:05 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 09:30:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:05 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 09:30:05 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 09:30:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:30:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:30:07 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 09:30:07 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 09:30:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:07 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 09:30:07 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 09:30:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:30:08 INFO - warning: no files found matching '*.txt' 09:30:08 INFO - no previously-included directories found matching 'build' 09:30:08 INFO - no previously-included directories found matching 'dist' 09:30:08 INFO - no previously-included directories found matching '.git*' 09:30:08 INFO - Installing collected packages: jsonschema, functools32 09:30:08 INFO - Running setup.py install for functools32 09:30:08 INFO - warning: no files found matching '*.txt' 09:30:08 INFO - no previously-included directories found matching 'build' 09:30:08 INFO - no previously-included directories found matching 'dist' 09:30:08 INFO - no previously-included directories found matching '.git*' 09:30:08 INFO - Successfully installed jsonschema functools32 09:30:08 INFO - Cleaning up... 09:30:08 INFO - Return code: 0 09:30:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:30:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:08 INFO - 'HOME': '/Users/cltbld', 09:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:08 INFO - 'LOGNAME': 'cltbld', 09:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:08 INFO - 'MOZ_NO_REMOTE': '1', 09:30:08 INFO - 'NO_EM_RESTART': '1', 09:30:08 INFO - 'PAGER': '/bin/cat', 09:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:08 INFO - 'PWD': '/builds/slave/test', 09:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:08 INFO - 'SHELL': '/bin/bash', 09:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:30:08 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:08 INFO - 'USER': 'cltbld', 09:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:08 INFO - 'XPC_FLAGS': '0x0', 09:30:08 INFO - 'XPC_SERVICE_NAME': '0', 09:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:08 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 09:30:08 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 09:30:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:08 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 09:30:08 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 09:30:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:11 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 09:30:11 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 09:30:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:11 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 09:30:11 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 09:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:30:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:12 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 09:30:12 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 09:30:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:12 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 09:30:12 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 09:30:12 INFO - Downloading docopt-0.6.1.tar.gz 09:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:30:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:30:12 INFO - Running setup.py install for blobuploader 09:30:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:30:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:30:12 INFO - Running setup.py install for requests 09:30:13 INFO - Running setup.py install for docopt 09:30:13 INFO - Successfully installed blobuploader requests docopt 09:30:13 INFO - Cleaning up... 09:30:13 INFO - Return code: 0 09:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:13 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:30:13 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:30:13 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:13 INFO - 'HOME': '/Users/cltbld', 09:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:13 INFO - 'LOGNAME': 'cltbld', 09:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:13 INFO - 'MOZ_NO_REMOTE': '1', 09:30:13 INFO - 'NO_EM_RESTART': '1', 09:30:13 INFO - 'PAGER': '/bin/cat', 09:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:13 INFO - 'PWD': '/builds/slave/test', 09:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:13 INFO - 'SHELL': '/bin/bash', 09:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:30:13 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:13 INFO - 'USER': 'cltbld', 09:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:13 INFO - 'XPC_FLAGS': '0x0', 09:30:13 INFO - 'XPC_SERVICE_NAME': '0', 09:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-KQ6j1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-FJySQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-dlIBVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-KRjxYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-AAV7uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-kmmmtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-T1uUD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-bHFtbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-8eFNvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-vrljes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-w4doA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-GeI1OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-EifLv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-t1GoxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-k3rgW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-7Tul45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-jc5eWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:30:15 INFO - Running setup.py install for manifestparser 09:30:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:30:15 INFO - Running setup.py install for mozcrash 09:30:15 INFO - Running setup.py install for mozdebug 09:30:15 INFO - Running setup.py install for mozdevice 09:30:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for mozfile 09:30:16 INFO - Running setup.py install for mozhttpd 09:30:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for mozinfo 09:30:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for mozInstall 09:30:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for mozleak 09:30:16 INFO - Running setup.py install for mozlog 09:30:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:30:16 INFO - Running setup.py install for moznetwork 09:30:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for mozprocess 09:30:17 INFO - Running setup.py install for mozprofile 09:30:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for mozrunner 09:30:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:30:17 INFO - Running setup.py install for mozscreenshot 09:30:17 INFO - Running setup.py install for moztest 09:30:17 INFO - Running setup.py install for mozversion 09:30:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:30:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:30:18 INFO - Cleaning up... 09:30:18 INFO - Return code: 0 09:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:30:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:30:18 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:18 INFO - 'HOME': '/Users/cltbld', 09:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:18 INFO - 'LOGNAME': 'cltbld', 09:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:18 INFO - 'MOZ_NO_REMOTE': '1', 09:30:18 INFO - 'NO_EM_RESTART': '1', 09:30:18 INFO - 'PAGER': '/bin/cat', 09:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:18 INFO - 'PWD': '/builds/slave/test', 09:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:18 INFO - 'SHELL': '/bin/bash', 09:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:30:18 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:18 INFO - 'USER': 'cltbld', 09:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:18 INFO - 'XPC_FLAGS': '0x0', 09:30:18 INFO - 'XPC_SERVICE_NAME': '0', 09:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:30:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-xnSw9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:30:18 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)) 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:30:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-H3PXKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:30:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-HKCpii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:30:18 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)) 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:30:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-2jwm5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:30:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-wSVAJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:30:18 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)) 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:30:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-AFTsJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:30:18 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)) 09:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:30:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-mC9G2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-AEoLB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-vrXyTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:30:19 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-X5fslu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-By5F2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:30:19 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-HWbiuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-I6luXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:30:19 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-uqsH0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-MakBqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:30:19 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)) 09:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:30:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-wyYnXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 09:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:30:20 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-1dv8VW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:30:20 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)) 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:30:20 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)) 09:30:20 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:20 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:30:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:30:20 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 09:30:20 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 09:30:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:20 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 09:30:20 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 09:30:22 INFO - Downloading blessings-1.6.tar.gz 09:30:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:30:22 INFO - Installing collected packages: blessings 09:30:22 INFO - Running setup.py install for blessings 09:30:22 INFO - Successfully installed blessings 09:30:22 INFO - Cleaning up... 09:30:22 INFO - Return code: 0 09:30:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:30:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:23 INFO - Reading from file tmpfile_stdout 09:30:23 INFO - Current package versions: 09:30:23 INFO - blessings == 1.6 09:30:23 INFO - blobuploader == 1.2.4 09:30:23 INFO - docopt == 0.6.1 09:30:23 INFO - functools32 == 3.2.3-2 09:30:23 INFO - jsonschema == 2.5.1 09:30:23 INFO - manifestparser == 1.1 09:30:23 INFO - mozInstall == 1.12 09:30:23 INFO - mozcrash == 0.17 09:30:23 INFO - mozdebug == 0.1 09:30:23 INFO - mozdevice == 0.48 09:30:23 INFO - mozfile == 1.2 09:30:23 INFO - mozhttpd == 0.7 09:30:23 INFO - mozinfo == 0.9 09:30:23 INFO - mozleak == 0.1 09:30:23 INFO - mozlog == 3.3 09:30:23 INFO - moznetwork == 0.27 09:30:23 INFO - mozprocess == 0.23 09:30:23 INFO - mozprofile == 0.28 09:30:23 INFO - mozrunner == 6.12 09:30:23 INFO - mozscreenshot == 0.1 09:30:23 INFO - mozsystemmonitor == 0.3 09:30:23 INFO - moztest == 0.7 09:30:23 INFO - mozversion == 1.4 09:30:23 INFO - psutil == 3.1.1 09:30:23 INFO - requests == 1.2.3 09:30:23 INFO - wsgiref == 0.1.2 09:30:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:30:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 09:30:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:23 INFO - 'HOME': '/Users/cltbld', 09:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:23 INFO - 'LOGNAME': 'cltbld', 09:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:23 INFO - 'MOZ_NO_REMOTE': '1', 09:30:23 INFO - 'NO_EM_RESTART': '1', 09:30:23 INFO - 'PAGER': '/bin/cat', 09:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:23 INFO - 'PWD': '/builds/slave/test', 09:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:23 INFO - 'SHELL': '/bin/bash', 09:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:30:23 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:23 INFO - 'USER': 'cltbld', 09:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:23 INFO - 'XPC_FLAGS': '0x0', 09:30:23 INFO - 'XPC_SERVICE_NAME': '0', 09:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 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)) 09:30:23 INFO - Cleaning up... 09:30:23 INFO - Return code: 0 09:30:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1051c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105259618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda915415d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, '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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 09:30:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:23 INFO - 'HOME': '/Users/cltbld', 09:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:23 INFO - 'LOGNAME': 'cltbld', 09:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:23 INFO - 'MOZ_NO_REMOTE': '1', 09:30:23 INFO - 'NO_EM_RESTART': '1', 09:30:23 INFO - 'PAGER': '/bin/cat', 09:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:23 INFO - 'PWD': '/builds/slave/test', 09:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:23 INFO - 'SHELL': '/bin/bash', 09:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:30:23 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:23 INFO - 'USER': 'cltbld', 09:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:23 INFO - 'XPC_FLAGS': '0x0', 09:30:23 INFO - 'XPC_SERVICE_NAME': '0', 09:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:30:23 INFO - Cleaning up... 09:30:23 INFO - Return code: 0 09:30:23 INFO - Running post-action listener: _resource_record_post_action 09:30:23 INFO - Running post-action listener: _start_resource_monitoring 09:30:23 INFO - Starting resource monitoring. 09:30:23 INFO - [mozharness: 2016-07-20 16:30:23.815255Z] Finished create-virtualenv step (success) 09:30:23 INFO - [mozharness: 2016-07-20 16:30:23.816239Z] Running install step. 09:30:23 INFO - Running pre-action listener: _resource_record_pre_action 09:30:23 INFO - Running main action method: install 09:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:30:24 INFO - Reading from file tmpfile_stdout 09:30:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:30:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:30:24 INFO - Reading from file tmpfile_stdout 09:30:24 INFO - Output received: 09:30:24 INFO - Usage: mozinstall [options] installer 09:30:24 INFO - Options: 09:30:24 INFO - -h, --help show this help message and exit 09:30:24 INFO - -d DEST, --destination=DEST 09:30:24 INFO - Directory to install application into. [default: 09:30:24 INFO - "/builds/slave/test"] 09:30:24 INFO - --app=APP Application being installed. [default: firefox] 09:30:24 INFO - mkdir: /builds/slave/test/build/application 09:30:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:30:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:30:59 INFO - Reading from file tmpfile_stdout 09:30:59 INFO - Output received: 09:30:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:30:59 INFO - Running post-action listener: _resource_record_post_action 09:30:59 INFO - [mozharness: 2016-07-20 16:30:59.723005Z] Finished install step (success) 09:30:59 INFO - [mozharness: 2016-07-20 16:30:59.723130Z] Running run-tests step. 09:30:59 INFO - Running pre-action listener: _resource_record_pre_action 09:30:59 INFO - Running main action method: run_tests 09:30:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:59 INFO - 2016-07-20 09:30:59.738 screenresolution[2841:301786] starting screenresolution argv=screenresolution get 09:30:59 INFO - 2016-07-20 09:30:59.741 screenresolution[2841:301786] Display 0: 1600x1200x32@60 09:30:59 INFO - 2016-07-20 09:30:59.746 screenresolution[2842:301788] starting screenresolution argv=screenresolution list 09:30:59 INFO - Available Modes on Display 0 09:30:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:59 INFO - Intel Iris: 09:30:59 INFO - Chipset Model: Intel Iris 09:30:59 INFO - Type: GPU 09:30:59 INFO - Bus: Built-In 09:30:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:59 INFO - Vendor: Intel (0x8086) 09:30:59 INFO - Device ID: 0x0a2e 09:30:59 INFO - Revision ID: 0x0009 09:30:59 INFO - Displays: 09:30:59 INFO - Display: 09:30:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:59 INFO - Display Serial Number: 1600x1200 60 09:30:59 INFO - Main Display: Yes 09:30:59 INFO - Mirror: Off 09:30:59 INFO - Online: Yes 09:30:59 INFO - Rotation: Supported 09:30:59 INFO - Return code: 0 09:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:30:59 INFO - Python 2.7.3 09:30:59 INFO - Return code: 0 09:30:59 INFO - grabbing minidump binary from tooltool 09:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:59 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 0x105128d30>, '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 0x104e8ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105120b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:30:59 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 09:30:59 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 09:30:59 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 09:31:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:00 INFO - Return code: 0 09:31:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:31:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:31:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 09:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 09:31:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RZcQc7b6N9/Render', 09:31:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:00 INFO - 'HOME': '/Users/cltbld', 09:31:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:00 INFO - 'LOGNAME': 'cltbld', 09:31:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:31:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:00 INFO - 'MOZ_NO_REMOTE': '1', 09:31:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:00 INFO - 'NO_EM_RESTART': '1', 09:31:00 INFO - 'PAGER': '/bin/cat', 09:31:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:00 INFO - 'PWD': '/builds/slave/test', 09:31:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:00 INFO - 'SHELL': '/bin/bash', 09:31:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ISRgdGLiPk/Listeners', 09:31:00 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 09:31:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:00 INFO - 'USER': 'cltbld', 09:31:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:00 INFO - 'XPC_FLAGS': '0x0', 09:31:00 INFO - 'XPC_SERVICE_NAME': '0', 09:31:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 09:31:00 INFO - mozversion application_buildid: 20160720075426 09:31:00 INFO - mozversion application_changeset: 03269dd72a7259f582977714559f0f4ef19988aa 09:31:00 INFO - mozversion application_display_name: Nightly 09:31:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:00 INFO - mozversion application_name: Firefox 09:31:00 INFO - mozversion application_remotingname: firefox 09:31:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:31:00 INFO - mozversion application_vendor: Mozilla 09:31:00 INFO - mozversion application_version: 50.0a1 09:31:00 INFO - mozversion platform_buildid: 20160720075426 09:31:00 INFO - mozversion platform_changeset: 03269dd72a7259f582977714559f0f4ef19988aa 09:31:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:31:00 INFO - mozversion platform_version: 50.0a1 09:31:00 INFO - using testdate: 1469032260 09:31:00 INFO - actual date: 1469032260 09:31:00 INFO - starting webserver on 'localhost:62985' 09:31:00 INFO - SUITE-START | Running 2 tests 09:31:00 INFO - TEST-START | dromaeo_css 09:31:00 INFO - operating with platform_type : mac_ 09:31:00 INFO - Initialising browser for dromaeo_css test... 09:31:00 INFO - TEST-INFO | started process 2852 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpv6ZhsN/profile http://localhost:62985/getInfo.html) 09:31:03 INFO - PROCESS | 2852 | __metrics Screen width/height:1600/1200 09:31:03 INFO - PROCESS | 2852 | colorDepth:24 09:31:03 INFO - PROCESS | 2852 | Browser inner width/height: 1024/689 09:31:03 INFO - PROCESS | 2852 | __metrics 09:31:03 ERROR - PROCESS | 2852 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:31:04 INFO - TEST-INFO | 2852: exit 0 09:31:04 INFO - Browser initialized. 09:31:04 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:04 INFO - TEST-INFO | started process 2855 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpv6ZhsN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:16 INFO - PROCESS | 2855 | RSS: Main: 255021056 09:31:16 INFO - PROCESS | 2855 | 09:32:05 INFO - PROCESS | 2855 | 1469032325676 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:34:17 INFO - PROCESS | 2855 | Cycle 1(1): loaded http://localhost:62985/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62985/tests/dromaeo/cssquery-ext.html) 09:34:18 INFO - PROCESS | 2855 | RSS: Main: 269058048 09:34:18 INFO - PROCESS | 2855 | 09:37:20 INFO - PROCESS | 2855 | Cycle 1(1): loaded http://localhost:62985/tests/dromaeo/cssquery-ext.html (next: http://localhost:62985/tests/dromaeo/cssquery-jquery.html) 09:37:21 INFO - PROCESS | 2855 | RSS: Main: 277880832 09:37:21 INFO - PROCESS | 2855 | 09:40:23 INFO - PROCESS | 2855 | Cycle 1(1): loaded http://localhost:62985/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62985/tests/dromaeo/cssquery-mootools.html) 09:40:24 INFO - PROCESS | 2855 | RSS: Main: 282513408 09:40:24 INFO - PROCESS | 2855 | 09:43:25 INFO - PROCESS | 2855 | Cycle 1(1): loaded http://localhost:62985/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62985/tests/dromaeo/cssquery-prototype.html) 09:43:26 INFO - PROCESS | 2855 | RSS: Main: 281456640 09:43:26 INFO - PROCESS | 2855 | 09:46:29 INFO - PROCESS | 2855 | Cycle 1(1): loaded http://localhost:62985/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62985/tests/dromaeo/cssquery-yui.html) 09:46:29 INFO - PROCESS | 2855 | RSS: Main: 285077504 09:46:29 INFO - PROCESS | 2855 | 09:49:32 INFO - PROCESS | 2855 | Cycle 1(1): loaded http://localhost:62985/tests/dromaeo/cssquery-yui.html (next: http://localhost:62985/tests/dromaeo/cssquery-dojo.html) 09:49:33 INFO - PROCESS | 2855 | RSS: Main: 285458432 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | __start_tp_report 09:49:33 INFO - PROCESS | 2855 | _x_x_mozilla_page_load 09:49:33 INFO - PROCESS | 2855 | _x_x_mozilla_page_load_details 09:49:33 INFO - PROCESS | 2855 | |i|pagename|runs| 09:49:33 INFO - PROCESS | 2855 | |0;dojo.html;3278.786878852875;3380.8140552269615;3387.745919056071;3407.846646900889;3426.8286585670726;5046.899062018758;5069.822556210534;5636.9999999999945;5898.262717160355;5932.080527518242;4689.296605509176;4741.245739076538;5843.977303971798;5931.406859314066;5978.850528736783;5189.299444574981;5260.447652996438;5279.234510995903;5323.22813192087;5395.514403703666;4847.733974279558;5367.624266301361;5429.077056900317;5430.5384042356445;5486.725663716798;5211.348581427322;5216.087184742655;5252.71110088945;5282.947170528284;5291.629585928968;5270.894582108374;5279.683219006872;5299.178627312773;5305.495977882095;5334.333208348957;5173.922391164136;5215.765290561896;5224.608154388436;5273.894522109536;5298.92051619226;5538.224648549206;5857.443542863463;5885.85285367865;5890.204822348971;5927.940720592782;6197.442230199259;6215.906761398583;6239.220097487814;6252.06219067139;6264.248290205159;6285.182926219608;6302.6218426894075;6316.084167795644;6330.65181415022;6333.651649159295;5821.27234095738;5900.704964751745;5901.76392944286;5967.791127310524;6026.276846778414;6335.873282534323;6339.746410143634;6347.301796802441;6347.39699728525;6395.2645445773405;6166.81499555014;6183.165272688219;6206.906896396557;6248;6255.46828519581;6161.106639537242;6199;6203.348648391944;6257;6282.685865706696;6234.594751341148;6248.062790581358;6256.74973001084;6283.842903927439;6284.748610055638;6225.782097626561;6250.156228909039;6255;6270.059491076375;6284.842878927972;4869.245266983623;4918.754062296871;4932.013597280558;4976.651634385558;4982.455089521488;4382.408374869352;4493.887652808706;4499.167653984024;4527;4544.318352247189;5936.7328470218945;6043.486303664238;6043.758249669964;6049.78825741097;6079.148919151322;6141.516692814182;6188.752449902044;6209.9068513972325;6226.533010024266;6239.875202496016;6021.397860214031;6029.125776762345;6030.638161710223;6051.152838602599;6069.15031895535;5499.065158922993;5511.944880551223;5520.033994051025;5545.500904918496;5547.306586676666;6230.158928544589;6304.779332723333;6307.873842523124;6332.30344662096;6447.194100737408;6218.875622487615;6266.185395898504;6269.592476489013;6292.276388215414;6309.400606942333;6409.8718025640155;6453.419192272718;6463.547551671338;6467.062275969864;6486.448651864455;6126.670355029888;6172.382761723792;6200.7519699211525;6230.470410015199;6283.308836027942;5753.1945527627;5858.2677165354335;6234.625922444487;6287.2140982377205;6295.622262664255;4836.975815121043;5755.539556835527;6239.78160764371;6259.937400626118;6262.248530176408;6070.271567411763;6120.203342909657;6168.907466387918;6255.218097737783;6258.530610204162;6004.159417681441;6102.1388476515085;6124.448799607879;6143.354947730424;6186.979148440457;6241.875162496815;6261.906071409024;6273.435390814849;6275.184226050585;6278.183836101278;5937.406259374028;5982.641041537521;6050.7882224123705;6149.354317796746;6168.475679567287 09:49:33 INFO - PROCESS | 2855 | |1;ext.html;489.1952737746407;521.5228066319318;539.5090467674418;548.1093223511793;559.2254727202823;39588.40617390738;42244.57755422445;42282.788586057024;42294;42328.94177645556;42868.785656071675;43349.7832510837;43561.34657980133;43600;43606.34590481145;9588.472634005127;9778;9791.902080979205;9792.804143917117;9795;8647.610857511396;9549.761255968588;9577.090176433263;9680.273979451535;9689.467079310634;9229.077092290785;9643.373180743229;9646.662366817163;9681.225501959862;9686.176674982635;9632.662856799978;9672.613095476172;9686.75783105421;9686.806263874754;9722.319437639368;9586.089321514444;9608.615655373847;9628.18160456362;9642.084002019797;9673.806523869483;40005.59994400048;40235.19529609421;40715.77852664395;40768.79615601933;40947.59052409497;3201.439748044083;3416.4875268709643;3446.60364058134;3450.913727156816;3625.583057948343;30636.54045189324;30853.228669283446;30883.845580772177;31113.533297000562;31310.758985640794;2493.1523282084177;3119.859606317721;3123.437781199383;3133.9843300783577;3273.852676629557;406237;408007;408701;410377;410834;33591.83204083964;33731;33750.83124584361;33780;33838.83080584631;34657.9602611922;34787.04310133667;34931.82534087364;35801;36001.639983600355;16902.83097169037;17058.914705426392;17210.31158753636;17225.827741722675;17263.13684315779;12995.090343676042;13008.479660813651;13071.281079540526;13100.803487947498;13104;16000.439984600716;16291;19924.60150797005;20090.89954550218;20146.59706805885;8190.058143313476;8289.22311025801;8310.376721745772;8343.916560834436;8475;894.5482531321771;903.8779764731809;937.2548823685187;963.643451922793;982.6904525074578;26356.341091472867;30361.24096897551;31302.686973129978;31335.68664313328;32041;918.7611221082433;1052.5631862777016;1053.6891616973012;1084.2572837606108;1112.260346869339;13043.347832608331;14120;15804.814638902128;15982.76025859613;16099.517014489584;684862.5756871183;691945;695388;698813.5415260441;699287;58796;59042.70478647665;59415;60118.69940650269;60340.396596034356;1220.4142011834192;1387.6600232942994;1395.9232242226774;1435.5119259451837;1440.6326386771307;26005;29256.7074329254;30034.54948175823;30156;30444.23889402694;49495.75252123716;52896.73551632217;52932.470675294295;52961.470385297194;52966.47033529615;617976;621233;621367;621849;621850;26712;26915.192544223704;27054.594181086904;27117.779699913157;27172.864135679196;43791.78104109459;45997.62007139791;48275.27587086126;48522;49604;38253.4261986076;39930.2013959725;40174.1965160701;40457.48877366261;41385.79307103566;40910;40964.975875602155;41294.1741165181;41494.17011659689;43059.78470107629;34349.31301374009;38669.806650966566;39660.81017569477;39737;39859.80420587387;33590.83204583961;36215.456768147975;36229.45655815112;36553.63446365609;36667;24545.63181552314;26481.073162439992;26859;26935;27074.458510830067 09:49:33 INFO - PROCESS | 2855 | |2;jquery.html;359.99640003599956;363.1375483227335;371.8847157381213;380.300247544518;393.7047214589058;19970;20027.399178024647;20543.280985165522;20692.586148277045;20911.790882091176;11595.188336816425;12213.511459541607;12272.815907761391;12296.938515307433;14863;5437.456254374551;5658.8019419320335;5685.914711279334;5706.400827913071;5723.771049158049;4002.1595464952393;5626.831195064155;6558.934410655904;6851.897221541681;6864.622445765481;5586.13414920688;6923.480738944574;6942.201646810606;6949.687264073129;6967.338102880219;5745.425457454263;5891.234139561872;5916.911246331309;5922.999999999978;5930.792422265222;4725.31482934976;5390.9191362129595;5430.5384042356445;5481.287432633732;5482.616216864821;13277.933610331984;15834.76247856283;19868.807871527755;25661.61507577388;26003.60994585083;1782.4474412932032;2100.453881990678;2232.386093824195;2292.495650956787;2405.8812652116676;8452.28155606774;10100.747481312952;10199.337043092175;10326.58693652253;11412.543498260142;1654.1481137214344;1706.48805358392;1760.7975082865378;1815.0471002723543;1855.5546668799525;129571.35214323868;132418;134186;135586.999999999;136167;11355;11539.422778938557;11600.709982250512;11675.88324116765;11746.471408786625;14929.402823886923;14946.70106597862;14979;15010.774838377432;15022.924885375503;8557.572121393905;8590.699325523701;8706.085860984505;8723.694670686495;8847.69033083839;6214.937850621526;8588.312934965188;8722.215000649845;8742.86885696715;8785.385023048453;4544.954550454519;4549.067441174551;4549.681522293407;4555.180067587799;4578.771061446914;1775.680377532031;1834.1379551610721;1924.4803902946405;1932.8550358723153;1952.248384372027;930.3998920696248;994.9950250248702;1010.8483727440943;1021.2442792333768;1023.3143793658267;15695.450659226872;17875.642487150446;18063;18503.021850302248;21476.04961594888;937.920276776468;949.6628696812533;978.4393891742659;990.6384169778123;1022.3508072373947;10943.726406839893;11260;11336.546538138382;11399;11400.030997365151;604506;617074.9755014624;619143;641774;643652;122566.77433225553;130217;130676;133775;134284;1008.8486726990864;1019.607451131305;1032.7727899862018;1054.4727636181908;1056.5245639462119;15175.468858589897;19171.32900348481;19269.325573604856;19308.806911931264;19537.902310488356;57306.71346643407;57793.4220657788;58795;59160.40839591549;59686.70156649189;85992.57003714942;86575.56712216651;87058;88707.22585548382;88717.22565548783;6604.768833091011;7903.81442783587;7936.960315198387;7954.801129971565;8195.631196596287;9116.954415227881;10771.192160588143;10914.672559823219;12785.680357991125;13208.273544954734;11009.394483303175;11081.445927703744;11295.265807722471;11324.94337528307;11372.601958931398;10346.689599312032;11322;11333;11377.431128443379;11544.422778860855;11617.6514704559;11708.824367634323;11826.704332391415;11913.702157446132;11967.581134660246;8093.19068093186;8129.593520323841;8335.374846886387;8382.455140415854;8483.78790530217;8435.325173985944;11352.659420217407;11442.027427668409;11524.481398337262;11813.114016448973 09:49:33 INFO - PROCESS | 2855 | |3;mootools.html;1441.3225783881574;1554.3549426987804;1800.2438975630234;1927.16168466717;1932.0822609260613;7935.92064079359;8535.573221338924;8567.78580535487;9017.954910225457;9047.321450891184;8607.741767746962;8777.253933415668;8779.780505487366;8788.69239576615;8805.779855503615;2356.575816353056;2384.177458776719;2390.3307074019303;2390.784829365357;2391.629297458888;2332.3819187915215;2369.3128992592215;2390.5457962986975;2404.5311164322966;2404.9759502404927;2354.234873666057;2385.976140238593;2397.9280621581293;2401.219603628828;2407.205622144688;2354.0701422937846;2364.2198074635417;2375.869327187004;2380.702412198475;2386.689730335063;2352.047420794581;2373.4778348763243;2373.9525209495714;2381.880905954695;2382.0590866607595;7983.640736166886;8284.461510001891;8509.149085091503;8914;8998;5154.639175257734;5366.114591092465;5372.006178856924;5373.408925018245;5382.650127741685;2983.597214376053;3567.8216089195694;3595;3613.5121758562523;3635.3092912346833;1064.4305296666246;1080.222239987208;1087.3258579680648;1095.5727266366123;1103.2938919091787;9329.626814927395;11548.826767598493;11608.767824643544;11699.18105732599;11728.706782330424;4502.752348620792;4598.839040633562;4745.335653008581;4769.880752981203;4782.258749893772;9940.801183976424;9987.600495980225;10034;10054.547545360476;10089.344192627455;5352.518273355339;5359.5712343012465;5372.140457526777;5377.973110134424;5382.165764306539;4404.9119017620105;4749.928751068736;4870.342503761947;4883.926741098886;4894.216925291937;3420.9486857697157;4003.6997225208224;4031.496062992126;4034.4150098236178;4046.907335019528;1275.1902541885784;1292.4184117147322;1293.6313150751992;1304.419533307688;1307.0589175793414;845.1421806866036;941.1905761045496;963.8216929867997;972.2950860626131;994.8060128275062;5928.288605367384;6009;6158.969205154035;6188.721507532172;6223.751049957951;421.7132350002028;428.1907195400633;433.754928465416;435.7908204062068;449.06818351919895;4020.8190631421658;4056.452378928807;4090.2842002650013;4142.958570414317;4166.479190101238;10602.681919542427;10681.03870651645;10800.621978230723;10820.783584328427;10838.67483975482;10606.363618182933;10730.946345268223;10731.141508679288;10734.516946737416;10737.892621073846;8197.672093116209;8211.671533138608;8253.174682531699;8314.127016663284;8329.08380078191;5844.1525978732;5966.940330596639;6075.696215189311;6078.787242446493;6220.160278362544;9596.23230141601;9928.156106731009;9942.900570994198;10065.748356290858;10165.13596344319;10118.392896426238;10203;10224.284300098921;10265.024822641837;10286.177105831664;7655.157932627518;7804.180561041009;7969.6015199240965;8178.959105204436;8258;8246.670133194844;8294.419390642597;8427.915720842959;8485.745427637181;8503.74488765338;6400.135981642418;6738.797836064926;7428.294312040348;7497.362724168507;7510.737124200627;7322.487425880137;7333.263511727588;7379.409647228102;7420.443466740124;7467.626618669154;7056.223815380406;7086.078809754699;7136.3220494052985;7170.749023784142;7174.641267936687;4510.526394728507;5080.771365288497;5150.562202212854;5171.870703232299;5203.401608815035;5061.721605311744;5169.638125331191;5228.37259528859;5254.264402983509;5360.276362690986 09:49:33 INFO - PROCESS | 2855 | |4;prototype.html;249.37282733924184;302.16602177988744;307.947648899687;316.0770549994018;318.8103078668192;4730.692504987173;5300.973495132519;5357.464253574651;5405.216243644669;5652.530342111055;4429.958959644481;4514.98412857107;5021.297018417425;5471.644343117695;5473.31583552056;3461.549998500198;4202.432671589342;4209.1581683663235;4224.97887510562;4238.766867822269;3320.1739043526086;3330.6835850594157;3949.7037722170808;4142.005918579534;4262.296721041024;3457.7925324480484;3970.8411663533425;4159.753732984185;4181.895452613679;4196.391523229146;3636.5817930937883;3812.923741525154;4153.688473364495;4156.8129434175535;4189.7695626740515;3248.9187770305693;3471.63547827477;3682.705383569335;3685.4840322354894;3717.8141092945243;4385.9692972151515;4492.865214043584;5339.902856343311;5415.620906536544;5433.347998240197;4213.852515161954;4352.73883566987;4803.375561177059;4804.5195480451675;4823.445303790074;5284.392294886099;5359.383670877821;5748.5975981681295;5752.756424994255;5782.768689252467;3881.2237552489614;3997.2205419943125;4014.4708833435043;4025.19496100781;4036.1120553478186;5462.907418516313;5619.7471113799975;6123.816285511397;6139.1712118863825;6174.876502469971;3798.3732684107367;4124.257633625976;4147.440095587057;4153.673942592158;4168.728043216454;3935.901602459904;4149.647279981175;4200.390943313202;4223.535411104776;4236.555161708038;4804.399450068741;4813.518648135158;4815.855524334275;4826.865686563655;4827.372441582572;5276.81531146408;5771.192033115367;5778.688524590198;5818.76724930998;5839.591228614041;1333.5997202936878;1354.5868510104513;1385.5427708856053;1420.2117824607442;1429.5568373804153;842.7640260727006;943.2139619444849;948.2224575847877;963.330485312701;979.3683074417104;4835.468098509161;5044.722540260321;5366.121857349896;5429.701366424805;5455.918161227585;5292.448494294903;5329.573634109262;5933.910991335133;5944.5838791284195;5957.884950842388;4055.6349928506575;4830.734309613005;5029.321041659402;5386.730663466811;5399.487048730364;2833.8158019729062;3103.7672174586996;3196.2169268528896;3204.198950262434;3205.887793927248;5195.610329225323;5303.893922121613;6233.1273621693;6258.374162583706;6278.4035516625845;5298.337707786527;5418.83743487696;5829.329627092854;6056.333803281724;6114.785982490592;6204.224471941007;6227.688615569294;6237.656928868865;6252.781152659817;6292;4680.227762419163;4807.519248075165;5285.418603953639;5506.054260970362;5928.733207005609;5212.514433386466;5951.434613711691;5994.291626886316;6003;6029.849253768691;5543.972280138573;6266.937330626635;6278.340774218641;6324.715387807468;6356.4279214870885;3647.8176091195865;3771.717121215865;3945.9408108877815;3954.7824869632445;3957.9907123683274;5283.1546952487415;5674.893395787947;5783.66975595607;6095.634261944298;6157.568970172045;5548.361938377138;5789.768409263583;5827.155062515996;5852.970735146297;5864.648121112747;5344.786208551614;5789.552611847038;5790.305163380422;5817.603775094031;5867.911981320198;4358.956410435855;4863.48933362006;5523.28197334344;5722.656640601577;5763.982606088003;4586.403767510203;5307.283516725192;5511.2364043505695;5586.050371436784;5857.74894933299;5651.519620832275;5678;5686.061799803151;5702.800401985911;5720.456556627114 09:49:33 INFO - PROCESS | 2855 | |5;yui.html;664.5945972956496;722.7687140115158;773.2229109744706;789.388224126302;847.6524624903791;13042.478300867953;15067.924660376697;15105.093694378318;15707.293171807267;15759.212039398028;14260.572182834532;14335.928320358384;14430.917681173902;14453;14458.132512049258;885.1502557544749;897.5063714956748;902.4630344944748;906.3157316226273;906.4561263242068;701.97543085992;880.5553195636195;902.7743064233941;913.0732306708663;914.7941713114562;962.990370096297;967.9564419601099;968.9806203875918;969.4086607169621;973.2165606686615;935.4854829843558;942.0626476655738;958.6405098088217;962.7544976031103;965.3725078698832;688.5111570784749;705.340506626306;711.7402148215864;713.0943701499119;714.1965289049818;14574;14656.706865862623;14877.628059298497;15044.247787610575;15123.395064197419;14496.782548261785;14541.854581454261;14554.345054472575;14766.778498322534;14786.40854365824;11754.29474231549;11933.582324618683;11983.700407489796;12113.818292725527;12339.93830030844;3751.5498140223076;3987.6809855211513;3994.6205110514456;4020.6783457323345;4036.374361973899;108003;111653.88346116515;111788.441057795;111803.44098279538;112033.43983280113;10865.565377384974;11101.777964440666;11282.830757538644;11689.415529223505;11704.590339338083;22254.777452225593;22855;22917;22970.42573935665;23072.653910191362;8111.310538604284;8234.505929644241;8234.752957411287;8236;8298.875516867252;3508.3509550733197;4561.977190114095;4582.587567118975;4608.953910460852;4734.573888349996;3104.2084268511385;3524.2599054198395;3614.6927511161844;3629.7822130672243;3636.29092326999;606.8604221029188;610.683995816417;616.5714828194361;626.8902941985226;636.8949123394588;610.2798697536861;653.6274323635482;666.73997141115;681.2710399872188;703.6868593475854;8190.672373105128;8589.355798315151;8708.962418869118;9357.719268421959;9481.383710058824;657.7533424965637;674.3694645506467;678.2810221165481;692.7783109404942;711.9572825630479;4418.977905110454;4593.63250939924;4621.815127394867;4634.142683603544;4650.906981860344;138676;146915;147680;147957.52042479438;148127.25936370465;83473;128365;132122;132440;132936;114660;115644;117801;119373.40313298377;121568;7681.961590192237;7947.443678942419;8628.82742345137;8695.60869760875;8895.955520222358;47736;50893.47319580419;57425.56436089131;58637;59186.70406647939;59140.1128983057;62475.6876215616;70328.64835675788;73676.78969630919;75732.86400704105;11498.425078746197;11523.8271425927;11837.467313971065;11855.288682679067;12117.939410302892;11083.501242443948;12772.233665980071;12790.68023299425;12849.935750321189;13210.603681889559;10133.493325333557;11968.222065565711;12155.088368372231;12480.251184928933;12874.227546347249;11570.652880413601;11699.2980421175;11718.765624687629;11733.237339572901;12342.50629974827;9963.402195868272;11742.236754610924;11862.347570883685;11868.940655296668;12303.876961230631;9972.50137493108;10031.047050530187;10070.647527336509;10137.695869123938;10509.789804204025;9368.67209647659;9455.621775128919;10397.948010259901;10426.061654451136;10441.843372349269 09:49:33 INFO - PROCESS | 2855 | __end_tp_report 09:49:33 INFO - PROCESS | 2855 | __start_cc_report 09:49:33 INFO - PROCESS | 2855 | _x_x_mozilla_cycle_collect,458 09:49:33 INFO - PROCESS | 2855 | __end_cc_report 09:49:33 INFO - PROCESS | 2855 | __startTimestamp1469033373164__endTimestamp 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | ------- Summary: start ------- 09:49:33 INFO - PROCESS | 2855 | Number of tests: 6 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | [#0] dojo.html Cycles:165 Average:5798.72 Median:6070.27 stddev:664.84 (11.0%) stddev-sans-first:636.81 09:49:33 INFO - PROCESS | 2855 | Values: 3278.8 3380.8 3387.7 3407.8 3426.8 5046.9 5069.8 5637.0 5898.3 5932.1 4689.3 4741.2 5844.0 5931.4 5978.9 5189.3 5260.4 5279.2 5323.2 5395.5 4847.7 5367.6 5429.1 5430.5 5486.7 5211.3 5216.1 5252.7 5282.9 5291.6 5270.9 5279.7 5299.2 5305.5 5334.3 5173.9 5215.8 5224.6 5273.9 5298.9 5538.2 5857.4 5885.9 5890.2 5927.9 6197.4 6215.9 6239.2 6252.1 6264.2 6285.2 6302.6 6316.1 6330.7 6333.7 5821.3 5900.7 5901.8 5967.8 6026.3 6335.9 6339.7 6347.3 6347.4 6395.3 6166.8 6183.2 6206.9 6248.0 6255.5 6161.1 6199.0 6203.3 6257.0 6282.7 6234.6 6248.1 6256.7 6283.8 6284.7 6225.8 6250.2 6255.0 6270.1 6284.8 4869.2 4918.8 4932.0 4976.7 4982.5 4382.4 4493.9 4499.2 4527.0 4544.3 5936.7 6043.5 6043.8 6049.8 6079.1 6141.5 6188.8 6209.9 6226.5 6239.9 6021.4 6029.1 6030.6 6051.2 6069.2 5499.1 5511.9 5520.0 5545.5 5547.3 6230.2 6304.8 6307.9 6332.3 6447.2 6218.9 6266.2 6269.6 6292.3 6309.4 6409.9 6453.4 6463.5 6467.1 6486.4 6126.7 6172.4 6200.8 6230.5 6283.3 5753.2 5858.3 6234.6 6287.2 6295.6 4837.0 5755.5 6239.8 6259.9 6262.2 6070.3 6120.2 6168.9 6255.2 6258.5 6004.2 6102.1 6124.4 6143.4 6187.0 6241.9 6261.9 6273.4 6275.2 6278.2 5937.4 5982.6 6050.8 6149.4 6168.5 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | [#1] ext.html Cycles:180 Average:69653.70 Median:223347.24 stddev:157468.92 (70.5%) stddev-sans-first:157825.03 09:49:33 INFO - PROCESS | 2855 | Values: 489.2 521.5 539.5 548.1 559.2 39588.4 42244.6 42282.8 42294.0 42328.9 42868.8 43349.8 43561.3 43600.0 43606.3 9588.5 9778.0 9791.9 9792.8 9795.0 8647.6 9549.8 9577.1 9680.3 9689.5 9229.1 9643.4 9646.7 9681.2 9686.2 9632.7 9672.6 9686.8 9686.8 9722.3 9586.1 9608.6 9628.2 9642.1 9673.8 40005.6 40235.2 40715.8 40768.8 40947.6 3201.4 3416.5 3446.6 3450.9 3625.6 30636.5 30853.2 30883.8 31113.5 31310.8 2493.2 3119.9 3123.4 3134.0 3273.9 406237.0 408007.0 408701.0 410377.0 410834.0 33591.8 33731.0 33750.8 33780.0 33838.8 34658.0 34787.0 34931.8 35801.0 36001.6 16902.8 17058.9 17210.3 17225.8 17263.1 12995.1 13008.5 13071.3 13100.8 13104.0 16000.4 16291.0 19924.6 20090.9 20146.6 8190.1 8289.2 8310.4 8343.9 8475.0 894.5 903.9 937.3 963.6 982.7 26356.3 30361.2 31302.7 31335.7 32041.0 918.8 1052.6 1053.7 1084.3 1112.3 13043.3 14120.0 15804.8 15982.8 16099.5 684862.6 691945.0 695388.0 698813.5 699287.0 58796.0 59042.7 59415.0 60118.7 60340.4 1220.4 1387.7 1395.9 1435.5 1440.6 26005.0 29256.7 30034.5 30156.0 30444.2 49495.8 52896.7 52932.5 52961.5 52966.5 617976.0 621233.0 621367.0 621849.0 621850.0 26712.0 26915.2 27054.6 27117.8 27172.9 43791.8 45997.6 48275.3 48522.0 49604.0 38253.4 39930.2 40174.2 40457.5 41385.8 40910.0 40965.0 41294.2 41494.2 43059.8 34349.3 38669.8 39660.8 39737.0 39859.8 33590.8 36215.5 36229.5 36553.6 36667.0 24545.6 26481.1 26859.0 26935.0 27074.5 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | [#2] jquery.html Cycles:180 Average:36267.50 Median:20285.34 stddev:104789.86 (516.6%) stddev-sans-first:105049.14 09:49:33 INFO - PROCESS | 2855 | Values: 360.0 363.1 371.9 380.3 393.7 19970.0 20027.4 20543.3 20692.6 20911.8 11595.2 12213.5 12272.8 12296.9 14863.0 5437.5 5658.8 5685.9 5706.4 5723.8 4002.2 5626.8 6558.9 6851.9 6864.6 5586.1 6923.5 6942.2 6949.7 6967.3 5745.4 5891.2 5916.9 5923.0 5930.8 4725.3 5390.9 5430.5 5481.3 5482.6 13277.9 15834.8 19868.8 25661.6 26003.6 1782.4 2100.5 2232.4 2292.5 2405.9 8452.3 10100.7 10199.3 10326.6 11412.5 1654.1 1706.5 1760.8 1815.0 1855.6 129571.4 132418.0 134186.0 135587.0 136167.0 11355.0 11539.4 11600.7 11675.9 11746.5 14929.4 14946.7 14979.0 15010.8 15022.9 8557.6 8590.7 8706.1 8723.7 8847.7 6214.9 8588.3 8722.2 8742.9 8785.4 4545.0 4549.1 4549.7 4555.2 4578.8 1775.7 1834.1 1924.5 1932.9 1952.2 930.4 995.0 1010.8 1021.2 1023.3 15695.5 17875.6 18063.0 18503.0 21476.0 937.9 949.7 978.4 990.6 1022.4 10943.7 11260.0 11336.5 11399.0 11400.0 604506.0 617075.0 619143.0 641774.0 643652.0 122566.8 130217.0 130676.0 133775.0 134284.0 1008.8 1019.6 1032.8 1054.5 1056.5 15175.5 19171.3 19269.3 19308.8 19537.9 57306.7 57793.4 58795.0 59160.4 59686.7 85992.6 86575.6 87058.0 88707.2 88717.2 6604.8 7903.8 7937.0 7954.8 8195.6 9117.0 10771.2 10914.7 12785.7 13208.3 11009.4 11081.4 11295.3 11324.9 11372.6 10346.7 11322.0 11333.0 11377.4 11544.4 11617.7 11708.8 11826.7 11913.7 11967.6 8093.2 8129.6 8335.4 8382.5 8483.8 8435.3 11352.7 11442.0 11524.5 11813.1 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | [#3] mootools.html Cycles:180 Average:5732.39 Median:4776.07 stddev:3201.98 (67.0%) stddev-sans-first:3194.72 09:49:33 INFO - PROCESS | 2855 | Values: 1441.3 1554.4 1800.2 1927.2 1932.1 7935.9 8535.6 8567.8 9018.0 9047.3 8607.7 8777.3 8779.8 8788.7 8805.8 2356.6 2384.2 2390.3 2390.8 2391.6 2332.4 2369.3 2390.5 2404.5 2405.0 2354.2 2386.0 2397.9 2401.2 2407.2 2354.1 2364.2 2375.9 2380.7 2386.7 2352.0 2373.5 2374.0 2381.9 2382.1 7983.6 8284.5 8509.1 8914.0 8998.0 5154.6 5366.1 5372.0 5373.4 5382.7 2983.6 3567.8 3595.0 3613.5 3635.3 1064.4 1080.2 1087.3 1095.6 1103.3 9329.6 11548.8 11608.8 11699.2 11728.7 4502.8 4598.8 4745.3 4769.9 4782.3 9940.8 9987.6 10034.0 10054.5 10089.3 5352.5 5359.6 5372.1 5378.0 5382.2 4404.9 4749.9 4870.3 4883.9 4894.2 3420.9 4003.7 4031.5 4034.4 4046.9 1275.2 1292.4 1293.6 1304.4 1307.1 845.1 941.2 963.8 972.3 994.8 5928.3 6009.0 6159.0 6188.7 6223.8 421.7 428.2 433.8 435.8 449.1 4020.8 4056.5 4090.3 4143.0 4166.5 10602.7 10681.0 10800.6 10820.8 10838.7 10606.4 10730.9 10731.1 10734.5 10737.9 8197.7 8211.7 8253.2 8314.1 8329.1 5844.2 5966.9 6075.7 6078.8 6220.2 9596.2 9928.2 9942.9 10065.7 10165.1 10118.4 10203.0 10224.3 10265.0 10286.2 7655.2 7804.2 7969.6 8179.0 8258.0 8246.7 8294.4 8427.9 8485.7 8503.7 6400.1 6738.8 7428.3 7497.4 7510.7 7322.5 7333.3 7379.4 7420.4 7467.6 7056.2 7086.1 7136.3 7170.7 7174.6 4510.5 5080.8 5150.6 5171.9 5203.4 5061.7 5169.6 5228.4 5254.3 5360.3 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | [#4] prototype.html Cycles:180 Average:4656.61 Median:5284.91 stddev:1439.60 (27.2%) stddev-sans-first:1405.11 09:49:33 INFO - PROCESS | 2855 | Values: 249.4 302.2 307.9 316.1 318.8 4730.7 5301.0 5357.5 5405.2 5652.5 4430.0 4515.0 5021.3 5471.6 5473.3 3461.5 4202.4 4209.2 4225.0 4238.8 3320.2 3330.7 3949.7 4142.0 4262.3 3457.8 3970.8 4159.8 4181.9 4196.4 3636.6 3812.9 4153.7 4156.8 4189.8 3248.9 3471.6 3682.7 3685.5 3717.8 4386.0 4492.9 5339.9 5415.6 5433.3 4213.9 4352.7 4803.4 4804.5 4823.4 5284.4 5359.4 5748.6 5752.8 5782.8 3881.2 3997.2 4014.5 4025.2 4036.1 5462.9 5619.7 6123.8 6139.2 6174.9 3798.4 4124.3 4147.4 4153.7 4168.7 3935.9 4149.6 4200.4 4223.5 4236.6 4804.4 4813.5 4815.9 4826.9 4827.4 5276.8 5771.2 5778.7 5818.8 5839.6 1333.6 1354.6 1385.5 1420.2 1429.6 842.8 943.2 948.2 963.3 979.4 4835.5 5044.7 5366.1 5429.7 5455.9 5292.4 5329.6 5933.9 5944.6 5957.9 4055.6 4830.7 5029.3 5386.7 5399.5 2833.8 3103.8 3196.2 3204.2 3205.9 5195.6 5303.9 6233.1 6258.4 6278.4 5298.3 5418.8 5829.3 6056.3 6114.8 6204.2 6227.7 6237.7 6252.8 6292.0 4680.2 4807.5 5285.4 5506.1 5928.7 5212.5 5951.4 5994.3 6003.0 6029.8 5544.0 6266.9 6278.3 6324.7 6356.4 3647.8 3771.7 3945.9 3954.8 3958.0 5283.2 5674.9 5783.7 6095.6 6157.6 5548.4 5789.8 5827.2 5853.0 5864.6 5344.8 5789.6 5790.3 5817.6 5867.9 4359.0 4863.5 5523.3 5722.7 5764.0 4586.4 5307.3 5511.2 5586.1 5857.7 5651.5 5678.0 5686.1 5702.8 5720.5 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - PROCESS | 2855 | [#5] yui.html Cycles:180 Average:23705.50 Median:3693.92 stddev:38511.42 (1042.6%) stddev-sans-first:38580.60 09:49:33 INFO - PROCESS | 2855 | Values: 664.6 722.8 773.2 789.4 847.7 13042.5 15067.9 15105.1 15707.3 15759.2 14260.6 14335.9 14430.9 14453.0 14458.1 885.2 897.5 902.5 906.3 906.5 702.0 880.6 902.8 913.1 914.8 963.0 968.0 969.0 969.4 973.2 935.5 942.1 958.6 962.8 965.4 688.5 705.3 711.7 713.1 714.2 14574.0 14656.7 14877.6 15044.2 15123.4 14496.8 14541.9 14554.3 14766.8 14786.4 11754.3 11933.6 11983.7 12113.8 12339.9 3751.5 3987.7 3994.6 4020.7 4036.4 108003.0 111653.9 111788.4 111803.4 112033.4 10865.6 11101.8 11282.8 11689.4 11704.6 22254.8 22855.0 22917.0 22970.4 23072.7 8111.3 8234.5 8234.8 8236.0 8298.9 3508.4 4562.0 4582.6 4609.0 4734.6 3104.2 3524.3 3614.7 3629.8 3636.3 606.9 610.7 616.6 626.9 636.9 610.3 653.6 666.7 681.3 703.7 8190.7 8589.4 8709.0 9357.7 9481.4 657.8 674.4 678.3 692.8 712.0 4419.0 4593.6 4621.8 4634.1 4650.9 138676.0 146915.0 147680.0 147957.5 148127.3 83473.0 128365.0 132122.0 132440.0 132936.0 114660.0 115644.0 117801.0 119373.4 121568.0 7682.0 7947.4 8628.8 8695.6 8896.0 47736.0 50893.5 57425.6 58637.0 59186.7 59140.1 62475.7 70328.6 73676.8 75732.9 11498.4 11523.8 11837.5 11855.3 12117.9 11083.5 12772.2 12790.7 12849.9 13210.6 10133.5 11968.2 12155.1 12480.3 12874.2 11570.7 11699.3 11718.8 11733.2 12342.5 9963.4 11742.2 11862.3 11868.9 12303.9 9972.5 10031.0 10070.6 10137.7 10509.8 9368.7 9455.6 10397.9 10426.1 10441.8 09:49:33 INFO - PROCESS | 2855 | -------- Summary: end -------- 09:49:33 INFO - PROCESS | 2855 | 09:49:33 INFO - TEST-INFO | 2855: exit 0 09:49:33 INFO - TEST-OK | dromaeo_css | took 1113448ms 09:49:33 INFO - TEST-START | kraken 09:49:33 INFO - operating with platform_type : mac_ 09:49:33 INFO - Initialising browser for kraken test... 09:49:33 INFO - TEST-INFO | started process 2859 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpOUMGkZ/profile http://localhost:62985/getInfo.html) 09:49:37 INFO - PROCESS | 2859 | __metrics Screen width/height:1600/1200 09:49:37 INFO - PROCESS | 2859 | colorDepth:24 09:49:37 INFO - PROCESS | 2859 | Browser inner width/height: 1024/689 09:49:37 INFO - PROCESS | 2859 | __metrics 09:49:37 ERROR - PROCESS | 2859 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:49:37 INFO - TEST-INFO | 2859: exit 0 09:49:37 INFO - Browser initialized. 09:49:37 INFO - Running cycle 1/1 for kraken test... 09:49:37 INFO - TEST-INFO | started process 2861 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpOUMGkZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:49 INFO - PROCESS | 2861 | RSS: Main: 255176704 09:49:49 INFO - PROCESS | 2861 | 09:49:52 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62985/tests/kraken/driver.html?testName=audio-beat-detection) 09:49:53 INFO - PROCESS | 2861 | RSS: Main: 267821056 09:49:53 INFO - PROCESS | 2861 | 09:49:56 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62985/tests/kraken/driver.html?testName=audio-dft) 09:49:56 INFO - PROCESS | 2861 | RSS: Main: 271781888 09:49:56 INFO - PROCESS | 2861 | 09:50:00 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62985/tests/kraken/driver.html?testName=audio-fft) 09:50:00 INFO - PROCESS | 2861 | RSS: Main: 272080896 09:50:00 INFO - PROCESS | 2861 | 09:50:02 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62985/tests/kraken/driver.html?testName=audio-oscillator) 09:50:03 INFO - PROCESS | 2861 | RSS: Main: 273035264 09:50:03 INFO - PROCESS | 2861 | 09:50:05 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62985/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:50:06 INFO - PROCESS | 2861 | RSS: Main: 273690624 09:50:06 INFO - PROCESS | 2861 | 09:50:09 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62985/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:09 INFO - PROCESS | 2861 | RSS: Main: 273788928 09:50:09 INFO - PROCESS | 2861 | 09:50:13 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62985/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:13 INFO - PROCESS | 2861 | RSS: Main: 274067456 09:50:13 INFO - PROCESS | 2861 | 09:50:16 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62985/tests/kraken/driver.html?testName=json-parse-financial) 09:50:17 INFO - PROCESS | 2861 | RSS: Main: 274157568 09:50:17 INFO - PROCESS | 2861 | 09:50:18 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62985/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:50:19 INFO - PROCESS | 2861 | RSS: Main: 273920000 09:50:19 INFO - PROCESS | 2861 | 09:50:21 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:50:22 INFO - PROCESS | 2861 | RSS: Main: 274055168 09:50:22 INFO - PROCESS | 2861 | 09:50:24 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:50:25 INFO - PROCESS | 2861 | RSS: Main: 274354176 09:50:25 INFO - PROCESS | 2861 | 09:50:28 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:50:28 INFO - PROCESS | 2861 | RSS: Main: 274440192 09:50:28 INFO - PROCESS | 2861 | 09:50:31 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:50:32 INFO - PROCESS | 2861 | RSS: Main: 274329600 09:50:32 INFO - PROCESS | 2861 | 09:50:33 INFO - PROCESS | 2861 | Cycle 1(1): loaded http://localhost:62985/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62985/tests/kraken/driver.html?testName=ai-astar) 09:50:34 INFO - PROCESS | 2861 | RSS: Main: 275578880 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | __start_tp_report 09:50:34 INFO - PROCESS | 2861 | _x_x_mozilla_page_load 09:50:34 INFO - PROCESS | 2861 | _x_x_mozilla_page_load_details 09:50:34 INFO - PROCESS | 2861 | |i|pagename|runs| 09:50:34 INFO - PROCESS | 2861 | |0;ai-astar;114;114;107;116;115;115;99;115;114;115 09:50:34 INFO - PROCESS | 2861 | |1;audio-beat-detection;146;108;165;184;120;128;125;109;160;110 09:50:34 INFO - PROCESS | 2861 | |2;audio-dft;199;206;193;216;274;281;227;205;195;197 09:50:34 INFO - PROCESS | 2861 | |3;audio-fft;72;81;78;181;87;82;61;60;133;59 09:50:34 INFO - PROCESS | 2861 | |4;audio-oscillator;69;68;124;70;79;90;113;68;75;116 09:50:34 INFO - PROCESS | 2861 | |5;imaging-gaussian-blur;136;129;91;106;136;82;78;131;89;127 09:50:34 INFO - PROCESS | 2861 | |6;imaging-darkroom;91;105;150;152;95;97;96;106;148;124 09:50:34 INFO - PROCESS | 2861 | |7;imaging-desaturate;98;95;154;105;90;90;101;138;89;103 09:50:34 INFO - PROCESS | 2861 | |8;json-parse-financial;57;58;55;56;57;56;57;54;57;56 09:50:34 INFO - PROCESS | 2861 | |9;json-stringify-tinderbox;69;74;77;74;76;92;113;115;105;112 09:50:34 INFO - PROCESS | 2861 | |10;stanford-crypto-aes;90;84;89;90;76;84;102;106;80;86 09:50:34 INFO - PROCESS | 2861 | |11;stanford-crypto-ccm;137;132;141;153;150;137;167;159;239;145 09:50:34 INFO - PROCESS | 2861 | |12;stanford-crypto-pbkdf2;166;170;166;161;168;163;163;167;177;164 09:50:34 INFO - PROCESS | 2861 | |13;stanford-crypto-sha256-iterative;69;65;67;66;70;67;67;66;70;70 09:50:34 INFO - PROCESS | 2861 | __end_tp_report 09:50:34 INFO - PROCESS | 2861 | __start_cc_report 09:50:34 INFO - PROCESS | 2861 | _x_x_mozilla_cycle_collect,1158 09:50:34 INFO - PROCESS | 2861 | __end_cc_report 09:50:34 INFO - PROCESS | 2861 | __startTimestamp1469033434181__endTimestamp 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | ------- Summary: start ------- 09:50:34 INFO - PROCESS | 2861 | Number of tests: 14 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#0] ai-astar Cycles:10 Average:112.40 Median:115.00 stddev:5.34 (4.6%) stddev-sans-first:5.63 09:50:34 INFO - PROCESS | 2861 | Values: 114.0 114.0 107.0 116.0 115.0 115.0 99.0 115.0 114.0 115.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#1] audio-beat-detection Cycles:10 Average:135.50 Median:137.00 stddev:26.77 (19.5%) stddev-sans-first:28.12 09:50:34 INFO - PROCESS | 2861 | Values: 146.0 108.0 165.0 184.0 120.0 128.0 125.0 109.0 160.0 110.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#2] audio-dft Cycles:10 Average:219.30 Median:211.00 stddev:32.39 (15.4%) stddev-sans-first:33.51 09:50:34 INFO - PROCESS | 2861 | Values: 199.0 206.0 193.0 216.0 274.0 281.0 227.0 205.0 195.0 197.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#3] audio-fft Cycles:10 Average:89.40 Median:75.00 stddev:38.66 (51.5%) stddev-sans-first:40.49 09:50:34 INFO - PROCESS | 2861 | Values: 72.0 81.0 78.0 181.0 87.0 82.0 61.0 60.0 133.0 59.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:69.50 stddev:22.21 (31.9%) stddev-sans-first:22.55 09:50:34 INFO - PROCESS | 2861 | Values: 69.0 68.0 124.0 70.0 79.0 90.0 113.0 68.0 75.0 116.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:107.00 stddev:23.73 (22.2%) stddev-sans-first:23.30 09:50:34 INFO - PROCESS | 2861 | Values: 136.0 129.0 91.0 106.0 136.0 82.0 78.0 131.0 89.0 127.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#6] imaging-darkroom Cycles:10 Average:116.40 Median:121.50 stddev:24.91 (20.5%) stddev-sans-first:24.67 09:50:34 INFO - PROCESS | 2861 | Values: 91.0 105.0 150.0 152.0 95.0 97.0 96.0 106.0 148.0 124.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#7] imaging-desaturate Cycles:10 Average:106.30 Median:89.50 stddev:21.98 (24.6%) stddev-sans-first:23.11 09:50:34 INFO - PROCESS | 2861 | Values: 98.0 95.0 154.0 105.0 90.0 90.0 101.0 138.0 89.0 103.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.16 (2.0%) stddev-sans-first:1.20 09:50:34 INFO - PROCESS | 2861 | Values: 57.0 58.0 55.0 56.0 57.0 56.0 57.0 54.0 57.0 56.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#9] json-stringify-tinderbox Cycles:10 Average:90.70 Median:74.00 stddev:18.80 (25.4%) stddev-sans-first:18.22 09:50:34 INFO - PROCESS | 2861 | Values: 69.0 74.0 77.0 74.0 76.0 92.0 113.0 115.0 105.0 112.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:85.00 stddev:9.24 (10.9%) stddev-sans-first:9.79 09:50:34 INFO - PROCESS | 2861 | Values: 90.0 84.0 89.0 90.0 76.0 84.0 102.0 106.0 80.0 86.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:151.50 stddev:31.11 (20.5%) stddev-sans-first:32.22 09:50:34 INFO - PROCESS | 2861 | Values: 137.0 132.0 141.0 153.0 150.0 137.0 167.0 159.0 239.0 145.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.50 Median:166.50 stddev:4.55 (2.7%) stddev-sans-first:4.82 09:50:34 INFO - PROCESS | 2861 | Values: 166.0 170.0 166.0 161.0 168.0 163.0 163.0 167.0 177.0 164.0 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - PROCESS | 2861 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.89 (2.8%) stddev-sans-first:1.94 09:50:34 INFO - PROCESS | 2861 | Values: 69.0 65.0 67.0 66.0 70.0 67.0 67.0 66.0 70.0 70.0 09:50:34 INFO - PROCESS | 2861 | -------- Summary: end -------- 09:50:34 INFO - PROCESS | 2861 | 09:50:34 INFO - TEST-INFO | 2861: exit 0 09:50:35 INFO - TEST-OK | kraken | took 61228ms 09:50:35 INFO - SUITE-END | took 1174s 09:50:35 INFO - Completed test suite (00:19:35) 09:50:35 INFO - javascript benchmark 09:50:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7330.479124916379, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3278.786878852875, 3380.8140552269615, 3387.745919056071, 3407.846646900889, 3426.8286585670726, 5046.899062018758, 5069.822556210534, 5636.9999999999945, 5898.262717160355, 5932.080527518242, 4689.296605509176, 4741.245739076538, 5843.977303971798, 5931.406859314066, 5978.850528736783, 5189.299444574981, 5260.447652996438, 5279.234510995903, 5323.22813192087, 5395.514403703666, 4847.733974279558, 5367.624266301361, 5429.077056900317, 5430.5384042356445, 5486.725663716798, 5211.348581427322, 5216.087184742655, 5252.71110088945, 5282.947170528284, 5291.629585928968, 5270.894582108374, 5279.683219006872, 5299.178627312773, 5305.495977882095, 5334.333208348957, 5173.922391164136, 5215.765290561896, 5224.608154388436, 5273.894522109536, 5298.92051619226, 5538.224648549206, 5857.443542863463, 5885.85285367865, 5890.204822348971, 5927.940720592782, 6197.442230199259, 6215.906761398583, 6239.220097487814, 6252.06219067139, 6264.248290205159, 6285.182926219608, 6302.6218426894075, 6316.084167795644, 6330.65181415022, 6333.651649159295, 5821.27234095738, 5900.704964751745, 5901.76392944286, 5967.791127310524, 6026.276846778414, 6335.873282534323, 6339.746410143634, 6347.301796802441, 6347.39699728525, 6395.2645445773405, 6166.81499555014, 6183.165272688219, 6206.906896396557, 6248.0, 6255.46828519581, 6161.106639537242, 6199.0, 6203.348648391944, 6257.0, 6282.685865706696, 6234.594751341148, 6248.062790581358, 6256.74973001084, 6283.842903927439, 6284.748610055638, 6225.782097626561, 6250.156228909039, 6255.0, 6270.059491076375, 6284.842878927972, 4869.245266983623, 4918.754062296871, 4932.013597280558, 4976.651634385558, 4982.455089521488, 4382.408374869352, 4493.887652808706, 4499.167653984024, 4527.0, 4544.318352247189, 5936.7328470218945, 6043.486303664238, 6043.758249669964, 6049.78825741097, 6079.148919151322, 6141.516692814182, 6188.752449902044, 6209.9068513972325, 6226.533010024266, 6239.875202496016, 6021.397860214031, 6029.125776762345, 6030.638161710223, 6051.152838602599, 6069.15031895535, 5499.065158922993, 5511.944880551223, 5520.033994051025, 5545.500904918496, 5547.306586676666, 6230.158928544589, 6304.779332723333, 6307.873842523124, 6332.30344662096, 6447.194100737408, 6218.875622487615, 6266.185395898504, 6269.592476489013, 6292.276388215414, 6309.400606942333, 6409.8718025640155, 6453.419192272718, 6463.547551671338, 6467.062275969864, 6486.448651864455, 6126.670355029888, 6172.382761723792, 6200.7519699211525, 6230.470410015199, 6283.308836027942, 5753.1945527627, 5858.2677165354335, 6234.625922444487, 6287.2140982377205, 6295.622262664255, 4836.975815121043, 5755.539556835527, 6239.78160764371, 6259.937400626118, 6262.248530176408, 6070.271567411763, 6120.203342909657, 6168.907466387918, 6255.218097737783, 6258.530610204162, 6004.159417681441, 6102.1388476515085, 6124.448799607879, 6143.354947730424, 6186.979148440457, 6241.875162496815, 6261.906071409024, 6273.435390814849, 6275.184226050585, 6278.183836101278, 5937.406259374028, 5982.641041537521, 6050.7882224123705, 6149.354317796746, 6168.475679567287], "value": 5756.473565460238, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.1952737746407, 521.5228066319318, 539.5090467674418, 548.1093223511793, 559.2254727202823, 39588.40617390738, 42244.57755422445, 42282.788586057024, 42294.0, 42328.94177645556, 42868.785656071675, 43349.7832510837, 43561.34657980133, 43600.0, 43606.34590481145, 9588.472634005127, 9778.0, 9791.902080979205, 9792.804143917117, 9795.0, 8647.610857511396, 9549.761255968588, 9577.090176433263, 9680.273979451535, 9689.467079310634, 9229.077092290785, 9643.373180743229, 9646.662366817163, 9681.225501959862, 9686.176674982635, 9632.662856799978, 9672.613095476172, 9686.75783105421, 9686.806263874754, 9722.319437639368, 9586.089321514444, 9608.615655373847, 9628.18160456362, 9642.084002019797, 9673.806523869483, 40005.59994400048, 40235.19529609421, 40715.77852664395, 40768.79615601933, 40947.59052409497, 3201.439748044083, 3416.4875268709643, 3446.60364058134, 3450.913727156816, 3625.583057948343, 30636.54045189324, 30853.228669283446, 30883.845580772177, 31113.533297000562, 31310.758985640794, 2493.1523282084177, 3119.859606317721, 3123.437781199383, 3133.9843300783577, 3273.852676629557, 406237.0, 408007.0, 408701.0, 410377.0, 410834.0, 33591.83204083964, 33731.0, 33750.83124584361, 33780.0, 33838.83080584631, 34657.9602611922, 34787.04310133667, 34931.82534087364, 35801.0, 36001.639983600355, 16902.83097169037, 17058.914705426392, 17210.31158753636, 17225.827741722675, 17263.13684315779, 12995.090343676042, 13008.479660813651, 13071.281079540526, 13100.803487947498, 13104.0, 16000.439984600716, 16291.0, 19924.60150797005, 20090.89954550218, 20146.59706805885, 8190.058143313476, 8289.22311025801, 8310.376721745772, 8343.916560834436, 8475.0, 894.5482531321771, 903.8779764731809, 937.2548823685187, 963.643451922793, 982.6904525074578, 26356.341091472867, 30361.24096897551, 31302.686973129978, 31335.68664313328, 32041.0, 918.7611221082433, 1052.5631862777016, 1053.6891616973012, 1084.2572837606108, 1112.260346869339, 13043.347832608331, 14120.0, 15804.814638902128, 15982.76025859613, 16099.517014489584, 684862.5756871183, 691945.0, 695388.0, 698813.5415260441, 699287.0, 58796.0, 59042.70478647665, 59415.0, 60118.69940650269, 60340.396596034356, 1220.4142011834192, 1387.6600232942994, 1395.9232242226774, 1435.5119259451837, 1440.6326386771307, 26005.0, 29256.7074329254, 30034.54948175823, 30156.0, 30444.23889402694, 49495.75252123716, 52896.73551632217, 52932.470675294295, 52961.470385297194, 52966.47033529615, 617976.0, 621233.0, 621367.0, 621849.0, 621850.0, 26712.0, 26915.192544223704, 27054.594181086904, 27117.779699913157, 27172.864135679196, 43791.78104109459, 45997.62007139791, 48275.27587086126, 48522.0, 49604.0, 38253.4261986076, 39930.2013959725, 40174.1965160701, 40457.48877366261, 41385.79307103566, 40910.0, 40964.975875602155, 41294.1741165181, 41494.17011659689, 43059.78470107629, 34349.31301374009, 38669.806650966566, 39660.81017569477, 39737.0, 39859.80420587387, 33590.83204583961, 36215.456768147975, 36229.45655815112, 36553.63446365609, 36667.0, 24545.63181552314, 26481.073162439992, 26859.0, 26935.0, 27074.458510830067], "value": 19578.807140857614, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.99640003599956, 363.1375483227335, 371.8847157381213, 380.300247544518, 393.7047214589058, 19970.0, 20027.399178024647, 20543.280985165522, 20692.586148277045, 20911.790882091176, 11595.188336816425, 12213.511459541607, 12272.815907761391, 12296.938515307433, 14863.0, 5437.456254374551, 5658.8019419320335, 5685.914711279334, 5706.400827913071, 5723.771049158049, 4002.1595464952393, 5626.831195064155, 6558.934410655904, 6851.897221541681, 6864.622445765481, 5586.13414920688, 6923.480738944574, 6942.201646810606, 6949.687264073129, 6967.338102880219, 5745.425457454263, 5891.234139561872, 5916.911246331309, 5922.999999999978, 5930.792422265222, 4725.31482934976, 5390.9191362129595, 5430.5384042356445, 5481.287432633732, 5482.616216864821, 13277.933610331984, 15834.76247856283, 19868.807871527755, 25661.61507577388, 26003.60994585083, 1782.4474412932032, 2100.453881990678, 2232.386093824195, 2292.495650956787, 2405.8812652116676, 8452.28155606774, 10100.747481312952, 10199.337043092175, 10326.58693652253, 11412.543498260142, 1654.1481137214344, 1706.48805358392, 1760.7975082865378, 1815.0471002723543, 1855.5546668799525, 129571.35214323868, 132418.0, 134186.0, 135586.999999999, 136167.0, 11355.0, 11539.422778938557, 11600.709982250512, 11675.88324116765, 11746.471408786625, 14929.402823886923, 14946.70106597862, 14979.0, 15010.774838377432, 15022.924885375503, 8557.572121393905, 8590.699325523701, 8706.085860984505, 8723.694670686495, 8847.69033083839, 6214.937850621526, 8588.312934965188, 8722.215000649845, 8742.86885696715, 8785.385023048453, 4544.954550454519, 4549.067441174551, 4549.681522293407, 4555.180067587799, 4578.771061446914, 1775.680377532031, 1834.1379551610721, 1924.4803902946405, 1932.8550358723153, 1952.248384372027, 930.3998920696248, 994.9950250248702, 1010.8483727440943, 1021.2442792333768, 1023.3143793658267, 15695.450659226872, 17875.642487150446, 18063.0, 18503.021850302248, 21476.04961594888, 937.920276776468, 949.6628696812533, 978.4393891742659, 990.6384169778123, 1022.3508072373947, 10943.726406839893, 11260.0, 11336.546538138382, 11399.0, 11400.030997365151, 604506.0, 617074.9755014624, 619143.0, 641774.0, 643652.0, 122566.77433225553, 130217.0, 130676.0, 133775.0, 134284.0, 1008.8486726990864, 1019.607451131305, 1032.7727899862018, 1054.4727636181908, 1056.5245639462119, 15175.468858589897, 19171.32900348481, 19269.325573604856, 19308.806911931264, 19537.902310488356, 57306.71346643407, 57793.4220657788, 58795.0, 59160.40839591549, 59686.70156649189, 85992.57003714942, 86575.56712216651, 87058.0, 88707.22585548382, 88717.22565548783, 6604.768833091011, 7903.81442783587, 7936.960315198387, 7954.801129971565, 8195.631196596287, 9116.954415227881, 10771.192160588143, 10914.672559823219, 12785.680357991125, 13208.273544954734, 11009.394483303175, 11081.445927703744, 11295.265807722471, 11324.94337528307, 11372.601958931398, 10346.689599312032, 11322.0, 11333.0, 11377.431128443379, 11544.422778860855, 11617.6514704559, 11708.824367634323, 11826.704332391415, 11913.702157446132, 11967.581134660246, 8093.19068093186, 8129.593520323841, 8335.374846886387, 8382.455140415854, 8483.78790530217, 8435.325173985944, 11352.659420217407, 11442.027427668409, 11524.481398337262, 11813.114016448973], "value": 9469.769158988089, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.3225783881574, 1554.3549426987804, 1800.2438975630234, 1927.16168466717, 1932.0822609260613, 7935.92064079359, 8535.573221338924, 8567.78580535487, 9017.954910225457, 9047.321450891184, 8607.741767746962, 8777.253933415668, 8779.780505487366, 8788.69239576615, 8805.779855503615, 2356.575816353056, 2384.177458776719, 2390.3307074019303, 2390.784829365357, 2391.629297458888, 2332.3819187915215, 2369.3128992592215, 2390.5457962986975, 2404.5311164322966, 2404.9759502404927, 2354.234873666057, 2385.976140238593, 2397.9280621581293, 2401.219603628828, 2407.205622144688, 2354.0701422937846, 2364.2198074635417, 2375.869327187004, 2380.702412198475, 2386.689730335063, 2352.047420794581, 2373.4778348763243, 2373.9525209495714, 2381.880905954695, 2382.0590866607595, 7983.640736166886, 8284.461510001891, 8509.149085091503, 8914.0, 8998.0, 5154.639175257734, 5366.114591092465, 5372.006178856924, 5373.408925018245, 5382.650127741685, 2983.597214376053, 3567.8216089195694, 3595.0, 3613.5121758562523, 3635.3092912346833, 1064.4305296666246, 1080.222239987208, 1087.3258579680648, 1095.5727266366123, 1103.2938919091787, 9329.626814927395, 11548.826767598493, 11608.767824643544, 11699.18105732599, 11728.706782330424, 4502.752348620792, 4598.839040633562, 4745.335653008581, 4769.880752981203, 4782.258749893772, 9940.801183976424, 9987.600495980225, 10034.0, 10054.547545360476, 10089.344192627455, 5352.518273355339, 5359.5712343012465, 5372.140457526777, 5377.973110134424, 5382.165764306539, 4404.9119017620105, 4749.928751068736, 4870.342503761947, 4883.926741098886, 4894.216925291937, 3420.9486857697157, 4003.6997225208224, 4031.496062992126, 4034.4150098236178, 4046.907335019528, 1275.1902541885784, 1292.4184117147322, 1293.6313150751992, 1304.419533307688, 1307.0589175793414, 845.1421806866036, 941.1905761045496, 963.8216929867997, 972.2950860626131, 994.8060128275062, 5928.288605367384, 6009.0, 6158.969205154035, 6188.721507532172, 6223.751049957951, 421.7132350002028, 428.1907195400633, 433.754928465416, 435.7908204062068, 449.06818351919895, 4020.8190631421658, 4056.452378928807, 4090.2842002650013, 4142.958570414317, 4166.479190101238, 10602.681919542427, 10681.03870651645, 10800.621978230723, 10820.783584328427, 10838.67483975482, 10606.363618182933, 10730.946345268223, 10731.141508679288, 10734.516946737416, 10737.892621073846, 8197.672093116209, 8211.671533138608, 8253.174682531699, 8314.127016663284, 8329.08380078191, 5844.1525978732, 5966.940330596639, 6075.696215189311, 6078.787242446493, 6220.160278362544, 9596.23230141601, 9928.156106731009, 9942.900570994198, 10065.748356290858, 10165.13596344319, 10118.392896426238, 10203.0, 10224.284300098921, 10265.024822641837, 10286.177105831664, 7655.157932627518, 7804.180561041009, 7969.6015199240965, 8178.959105204436, 8258.0, 8246.670133194844, 8294.419390642597, 8427.915720842959, 8485.745427637181, 8503.74488765338, 6400.135981642418, 6738.797836064926, 7428.294312040348, 7497.362724168507, 7510.737124200627, 7322.487425880137, 7333.263511727588, 7379.409647228102, 7420.443466740124, 7467.626618669154, 7056.223815380406, 7086.078809754699, 7136.3220494052985, 7170.749023784142, 7174.641267936687, 4510.526394728507, 5080.771365288497, 5150.562202212854, 5171.870703232299, 5203.401608815035, 5061.721605311744, 5169.638125331191, 5228.37259528859, 5254.264402983509, 5360.276362690986], "value": 4541.480557100903, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [249.37282733924184, 302.16602177988744, 307.947648899687, 316.0770549994018, 318.8103078668192, 4730.692504987173, 5300.973495132519, 5357.464253574651, 5405.216243644669, 5652.530342111055, 4429.958959644481, 4514.98412857107, 5021.297018417425, 5471.644343117695, 5473.31583552056, 3461.549998500198, 4202.432671589342, 4209.1581683663235, 4224.97887510562, 4238.766867822269, 3320.1739043526086, 3330.6835850594157, 3949.7037722170808, 4142.005918579534, 4262.296721041024, 3457.7925324480484, 3970.8411663533425, 4159.753732984185, 4181.895452613679, 4196.391523229146, 3636.5817930937883, 3812.923741525154, 4153.688473364495, 4156.8129434175535, 4189.7695626740515, 3248.9187770305693, 3471.63547827477, 3682.705383569335, 3685.4840322354894, 3717.8141092945243, 4385.9692972151515, 4492.865214043584, 5339.902856343311, 5415.620906536544, 5433.347998240197, 4213.852515161954, 4352.73883566987, 4803.375561177059, 4804.5195480451675, 4823.445303790074, 5284.392294886099, 5359.383670877821, 5748.5975981681295, 5752.756424994255, 5782.768689252467, 3881.2237552489614, 3997.2205419943125, 4014.4708833435043, 4025.19496100781, 4036.1120553478186, 5462.907418516313, 5619.7471113799975, 6123.816285511397, 6139.1712118863825, 6174.876502469971, 3798.3732684107367, 4124.257633625976, 4147.440095587057, 4153.673942592158, 4168.728043216454, 3935.901602459904, 4149.647279981175, 4200.390943313202, 4223.535411104776, 4236.555161708038, 4804.399450068741, 4813.518648135158, 4815.855524334275, 4826.865686563655, 4827.372441582572, 5276.81531146408, 5771.192033115367, 5778.688524590198, 5818.76724930998, 5839.591228614041, 1333.5997202936878, 1354.5868510104513, 1385.5427708856053, 1420.2117824607442, 1429.5568373804153, 842.7640260727006, 943.2139619444849, 948.2224575847877, 963.330485312701, 979.3683074417104, 4835.468098509161, 5044.722540260321, 5366.121857349896, 5429.701366424805, 5455.918161227585, 5292.448494294903, 5329.573634109262, 5933.910991335133, 5944.5838791284195, 5957.884950842388, 4055.6349928506575, 4830.734309613005, 5029.321041659402, 5386.730663466811, 5399.487048730364, 2833.8158019729062, 3103.7672174586996, 3196.2169268528896, 3204.198950262434, 3205.887793927248, 5195.610329225323, 5303.893922121613, 6233.1273621693, 6258.374162583706, 6278.4035516625845, 5298.337707786527, 5418.83743487696, 5829.329627092854, 6056.333803281724, 6114.785982490592, 6204.224471941007, 6227.688615569294, 6237.656928868865, 6252.781152659817, 6292.0, 4680.227762419163, 4807.519248075165, 5285.418603953639, 5506.054260970362, 5928.733207005609, 5212.514433386466, 5951.434613711691, 5994.291626886316, 6003.0, 6029.849253768691, 5543.972280138573, 6266.937330626635, 6278.340774218641, 6324.715387807468, 6356.4279214870885, 3647.8176091195865, 3771.717121215865, 3945.9408108877815, 3954.7824869632445, 3957.9907123683274, 5283.1546952487415, 5674.893395787947, 5783.66975595607, 6095.634261944298, 6157.568970172045, 5548.361938377138, 5789.768409263583, 5827.155062515996, 5852.970735146297, 5864.648121112747, 5344.786208551614, 5789.552611847038, 5790.305163380422, 5817.603775094031, 5867.911981320198, 4358.956410435855, 4863.48933362006, 5523.28197334344, 5722.656640601577, 5763.982606088003, 4586.403767510203, 5307.283516725192, 5511.2364043505695, 5586.050371436784, 5857.74894933299, 5651.519620832275, 5678.0, 5686.061799803151, 5702.800401985911, 5720.456556627114], "value": 4198.793673688235, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.5945972956496, 722.7687140115158, 773.2229109744706, 789.388224126302, 847.6524624903791, 13042.478300867953, 15067.924660376697, 15105.093694378318, 15707.293171807267, 15759.212039398028, 14260.572182834532, 14335.928320358384, 14430.917681173902, 14453.0, 14458.132512049258, 885.1502557544749, 897.5063714956748, 902.4630344944748, 906.3157316226273, 906.4561263242068, 701.97543085992, 880.5553195636195, 902.7743064233941, 913.0732306708663, 914.7941713114562, 962.990370096297, 967.9564419601099, 968.9806203875918, 969.4086607169621, 973.2165606686615, 935.4854829843558, 942.0626476655738, 958.6405098088217, 962.7544976031103, 965.3725078698832, 688.5111570784749, 705.340506626306, 711.7402148215864, 713.0943701499119, 714.1965289049818, 14574.0, 14656.706865862623, 14877.628059298497, 15044.247787610575, 15123.395064197419, 14496.782548261785, 14541.854581454261, 14554.345054472575, 14766.778498322534, 14786.40854365824, 11754.29474231549, 11933.582324618683, 11983.700407489796, 12113.818292725527, 12339.93830030844, 3751.5498140223076, 3987.6809855211513, 3994.6205110514456, 4020.6783457323345, 4036.374361973899, 108003.0, 111653.88346116515, 111788.441057795, 111803.44098279538, 112033.43983280113, 10865.565377384974, 11101.777964440666, 11282.830757538644, 11689.415529223505, 11704.590339338083, 22254.777452225593, 22855.0, 22917.0, 22970.42573935665, 23072.653910191362, 8111.310538604284, 8234.505929644241, 8234.752957411287, 8236.0, 8298.875516867252, 3508.3509550733197, 4561.977190114095, 4582.587567118975, 4608.953910460852, 4734.573888349996, 3104.2084268511385, 3524.2599054198395, 3614.6927511161844, 3629.7822130672243, 3636.29092326999, 606.8604221029188, 610.683995816417, 616.5714828194361, 626.8902941985226, 636.8949123394588, 610.2798697536861, 653.6274323635482, 666.73997141115, 681.2710399872188, 703.6868593475854, 8190.672373105128, 8589.355798315151, 8708.962418869118, 9357.719268421959, 9481.383710058824, 657.7533424965637, 674.3694645506467, 678.2810221165481, 692.7783109404942, 711.9572825630479, 4418.977905110454, 4593.63250939924, 4621.815127394867, 4634.142683603544, 4650.906981860344, 138676.0, 146915.0, 147680.0, 147957.52042479438, 148127.25936370465, 83473.0, 128365.0, 132122.0, 132440.0, 132936.0, 114660.0, 115644.0, 117801.0, 119373.40313298377, 121568.0, 7681.961590192237, 7947.443678942419, 8628.82742345137, 8695.60869760875, 8895.955520222358, 47736.0, 50893.47319580419, 57425.56436089131, 58637.0, 59186.70406647939, 59140.1128983057, 62475.6876215616, 70328.64835675788, 73676.78969630919, 75732.86400704105, 11498.425078746197, 11523.8271425927, 11837.467313971065, 11855.288682679067, 12117.939410302892, 11083.501242443948, 12772.233665980071, 12790.68023299425, 12849.935750321189, 13210.603681889559, 10133.493325333557, 11968.222065565711, 12155.088368372231, 12480.251184928933, 12874.227546347249, 11570.652880413601, 11699.2980421175, 11718.765624687629, 11733.237339572901, 12342.50629974827, 9963.402195868272, 11742.236754610924, 11862.347570883685, 11868.940655296668, 12303.876961230631, 9972.50137493108, 10031.047050530187, 10070.647527336509, 10137.695869123938, 10509.789804204025, 9368.67209647659, 9455.621775128919, 10397.948010259901, 10426.061654451136, 10441.843372349269], "value": 7623.352522081324, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 107.0, 116.0, 115.0, 115.0, 99.0, 115.0, 114.0, 115.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 108.0, 165.0, 184.0, 120.0, 128.0, 125.0, 109.0, 160.0, 110.0], "value": 135.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 206.0, 193.0, 216.0, 274.0, 281.0, 227.0, 205.0, 195.0, 197.0], "value": 219.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 78.0, 181.0, 87.0, 82.0, 61.0, 60.0, 133.0, 59.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 124.0, 70.0, 79.0, 90.0, 113.0, 68.0, 75.0, 116.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 129.0, 91.0, 106.0, 136.0, 82.0, 78.0, 131.0, 89.0, 127.0], "value": 110.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 105.0, 150.0, 152.0, 95.0, 97.0, 96.0, 106.0, 148.0, 124.0], "value": 116.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 154.0, 105.0, 90.0, 90.0, 101.0, 138.0, 89.0, 103.0], "value": 106.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 55.0, 56.0, 57.0, 56.0, 57.0, 54.0, 57.0, 56.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 77.0, 74.0, 76.0, 92.0, 113.0, 115.0, 105.0, 112.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 84.0, 89.0, 90.0, 76.0, 84.0, 102.0, 106.0, 80.0, 86.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 141.0, 153.0, 150.0, 137.0, 167.0, 159.0, 239.0, 145.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 170.0, 166.0, 161.0, 168.0, 163.0, 163.0, 167.0, 177.0, 164.0], "value": 166.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 67.0, 66.0, 70.0, 67.0, 67.0, 66.0, 70.0, 70.0], "value": 67.7, "unit": "score"}]}]} 09:50:35 INFO - Return code: 0 09:50:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:50:35 ERROR - # TBPL SUCCESS # 09:50:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:50:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:50:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:50:35 INFO - 2016-07-20 09:50:35.529 screenresolution[2866:304274] starting screenresolution argv=screenresolution get 09:50:35 INFO - 2016-07-20 09:50:35.531 screenresolution[2866:304274] Display 0: 1600x1200x32@60 09:50:35 INFO - 2016-07-20 09:50:35.537 screenresolution[2867:304277] starting screenresolution argv=screenresolution list 09:50:35 INFO - Available Modes on Display 0 09:50:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:50:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:50:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:50:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:50:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:50:35 INFO - Intel Iris: 09:50:35 INFO - Chipset Model: Intel Iris 09:50:35 INFO - Type: GPU 09:50:35 INFO - Bus: Built-In 09:50:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:50:35 INFO - Vendor: Intel (0x8086) 09:50:35 INFO - Device ID: 0x0a2e 09:50:35 INFO - Revision ID: 0x0009 09:50:35 INFO - Displays: 09:50:35 INFO - Display: 09:50:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:50:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:50:35 INFO - Display Serial Number: 1600x1200 60 09:50:35 INFO - Main Display: Yes 09:50:35 INFO - Mirror: Off 09:50:35 INFO - Online: Yes 09:50:35 INFO - Rotation: Supported 09:50:35 INFO - Return code: 0 09:50:35 INFO - Running post-action listener: _resource_record_post_action 09:50:35 INFO - [mozharness: 2016-07-20 16:50:35.774070Z] Finished run-tests step (success) 09:50:35 INFO - Running post-run listener: _resource_record_post_run 09:50:35 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32768; Write bytes: 707276288; Read time: 0; Write time: 2138 09:50:35 INFO - TinderboxPrint: CPU usage
54.2% 09:50:35 INFO - TinderboxPrint: I/O read bytes / time
32,768 / 0 09:50:35 INFO - TinderboxPrint: I/O write bytes / time
707,276,288 / 2,138 09:50:35 INFO - TinderboxPrint: CPU idle
2,218.0 (45.8%) 09:50:35 INFO - TinderboxPrint: CPU system
985.0 (20.3%) 09:50:35 INFO - TinderboxPrint: CPU user
1,642.7 (33.9%) 09:50:35 INFO - TinderboxPrint: Swap in / out
407,314,432 / 0 09:50:35 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 388860416; Write bytes: 384067584; Read time: 29502; Write time: 684 09:50:35 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 32768; Write bytes: 295851520; Read time: 0; Write time: 1403 09:50:36 INFO - Running post-run listener: _upload_blobber_files 09:50:36 INFO - Blob upload gear active. 09:50:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:50:36 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 09:50:36 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:50:36 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:50:36 INFO - (blobuploader) - INFO - Open directory for files ... 09:50:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:50:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:50:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:37 INFO - (blobuploader) - INFO - Done attempting. 09:50:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:50:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:50:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:38 INFO - (blobuploader) - INFO - Done attempting. 09:50:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:50:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:50:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:50:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:50:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:50:42 INFO - (blobuploader) - INFO - Done attempting. 09:50:42 INFO - (blobuploader) - INFO - Iteration through files over. 09:50:42 INFO - Return code: 0 09:50:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:50:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:50:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8e2fe286cc6ce2ac080728cac0e487ef81c423378114268705efd3660406ee39caca44a13f981574baf187d35f1b09ccafcf2b93d1e5c17a632684a8fda5358", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/449ae0a252ba0e0ab6629b10fae2754cb0507d275bcf18ecb874fee893da6ac8af59e508acfdbf82907c9b9888ed16bc87432111d71608f74c7d621b32e44122", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/988c03c8ec02d71e2a33adde64127454a01a2b1d557571bc65c2f5b46562857f7763f3f802e39065279deede4e2b74ce77f44300cfca8a2b080e7cb06301c2d9"} 09:50:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:50:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:50:42 INFO - Contents: 09:50:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8e2fe286cc6ce2ac080728cac0e487ef81c423378114268705efd3660406ee39caca44a13f981574baf187d35f1b09ccafcf2b93d1e5c17a632684a8fda5358", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/449ae0a252ba0e0ab6629b10fae2754cb0507d275bcf18ecb874fee893da6ac8af59e508acfdbf82907c9b9888ed16bc87432111d71608f74c7d621b32e44122", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/988c03c8ec02d71e2a33adde64127454a01a2b1d557571bc65c2f5b46562857f7763f3f802e39065279deede4e2b74ce77f44300cfca8a2b080e7cb06301c2d9"} 09:50:42 INFO - Running post-run listener: copy_logs_to_upload_dir 09:50:42 INFO - Copying logs to upload dir... 09:50:42 INFO - mkdir: /builds/slave/test/build/upload/logs 09:50:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.649539 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-07-20 09:50:43.102954) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:43.107516) ========= 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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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/Firefox/sha512/b8e2fe286cc6ce2ac080728cac0e487ef81c423378114268705efd3660406ee39caca44a13f981574baf187d35f1b09ccafcf2b93d1e5c17a632684a8fda5358", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/449ae0a252ba0e0ab6629b10fae2754cb0507d275bcf18ecb874fee893da6ac8af59e508acfdbf82907c9b9888ed16bc87432111d71608f74c7d621b32e44122", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/988c03c8ec02d71e2a33adde64127454a01a2b1d557571bc65c2f5b46562857f7763f3f802e39065279deede4e2b74ce77f44300cfca8a2b080e7cb06301c2d9"} build_url:https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/WPc2tUR5TEKT7AkBqkynog/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8e2fe286cc6ce2ac080728cac0e487ef81c423378114268705efd3660406ee39caca44a13f981574baf187d35f1b09ccafcf2b93d1e5c17a632684a8fda5358", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/449ae0a252ba0e0ab6629b10fae2754cb0507d275bcf18ecb874fee893da6ac8af59e508acfdbf82907c9b9888ed16bc87432111d71608f74c7d621b32e44122", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/988c03c8ec02d71e2a33adde64127454a01a2b1d557571bc65c2f5b46562857f7763f3f802e39065279deede4e2b74ce77f44300cfca8a2b080e7cb06301c2d9"}' ========= master_lag: 0.55 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:43.672242) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:43.672564) ========= 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.RZcQc7b6N9/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.ISRgdGLiPk/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005366 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-20 09:50:43.698797) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 09:50:43.699190) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-20 09:50:43.699568) ========= ========= Total master_lag: 0.75 =========