builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0061 starttime: 1480511273.12 results: success (0) buildid: 20161130031744 builduid: 8ed20d6666f94d7c89a2f9a84369bc73 revision: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.116696) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.117101) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.117389) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.254609) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.255272) ========= 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.p3ZW4yUq41/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.PipkiCu7rl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-11-30 05:07:53-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.1M=0.001s 2016-11-30 05:07:53 (13.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088204 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.377720) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.378004) ========= 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.p3ZW4yUq41/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.PipkiCu7rl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.026844 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.434203) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:53.434553) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p3ZW4yUq41/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.PipkiCu7rl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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-11-30 05:07:53,530 truncating revision to first 12 chars 2016-11-30 05:07:53,530 Setting DEBUG logging. 2016-11-30 05:07:53,530 attempt 1/10 2016-11-30 05:07:53,530 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d27c9c1d6beb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 05:07:53,825 unpacking tar archive at: mozilla-aurora-d27c9c1d6beb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.566358 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:54.024867) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:54.025158) ========= script_repo_revision: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:54.025535) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:54.025805) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 05:07:54.045740) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-30 05:07:54.046092) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.p3ZW4yUq41/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.PipkiCu7rl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:07:54 INFO - MultiFileLogger online at 20161130 05:07:54 in /builds/slave/test 05:07:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:07:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:07:54 INFO - {'append_to_log': False, 05:07:54 INFO - 'base_work_dir': '/builds/slave/test', 05:07:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:07:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:07:54 INFO - 'branch': 'Mozilla-Aurora', 05:07:54 INFO - 'buildbot_json_path': 'buildprops.json', 05:07:54 INFO - 'config_files': ('talos/mac_config.py',), 05:07:54 INFO - 'default_actions': ('clobber', 05:07:54 INFO - 'read-buildbot-config', 05:07:54 INFO - 'download-and-extract', 05:07:54 INFO - 'populate-webroot', 05:07:54 INFO - 'create-virtualenv', 05:07:54 INFO - 'install', 05:07:54 INFO - 'run-tests'), 05:07:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:07:54 INFO - 'download_minidump_stackwalk': True, 05:07:54 INFO - 'download_symbols': 'ondemand', 05:07:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:07:54 INFO - 'tooltool.py': '/tools/tooltool.py', 05:07:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:07:54 INFO - '/tools/misc-python/virtualenv.py')}, 05:07:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:07:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:07:54 INFO - 'installer_path': 'installer.exe', 05:07:54 INFO - 'log_level': 'info', 05:07:54 INFO - 'log_name': 'talos', 05:07:54 INFO - 'log_to_console': True, 05:07:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:07:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:07:54 INFO - 'opt_config_files': (), 05:07:54 INFO - 'pip_index': False, 05:07:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:07:54 INFO - 'cmd': ('bash', 05:07:54 INFO - '-c', 05:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:07:54 INFO - 'enabled': True, 05:07:54 INFO - 'halt_on_failure': False, 05:07:54 INFO - 'name': 'check_screen_resolution'},), 05:07:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:07:54 INFO - 'cmd': ('bash', 05:07:54 INFO - '-c', 05:07:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:07:54 INFO - 'enabled': True, 05:07:54 INFO - 'halt_on_failure': False, 05:07:54 INFO - 'name': 'check_screen_resolution'},), 05:07:54 INFO - 'run_cmd_checks_enabled': True, 05:07:54 INFO - 'sps_profile': False, 05:07:54 INFO - 'sps_profile_interval': 0, 05:07:54 INFO - 'suite': 'dromaeojs-e10s', 05:07:54 INFO - 'system_bits': '32', 05:07:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:07:54 INFO - 'title': 't-yosemite-r7-0061', 05:07:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:07:54 INFO - 'use_talos_json': True, 05:07:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:07:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:07:54 INFO - 'work_dir': 'build'} 05:07:54 INFO - [mozharness: 2016-11-30 13:07:54.253979Z] Running clobber step. 05:07:54 INFO - Running pre-action listener: _resource_record_pre_action 05:07:54 INFO - Running main action method: clobber 05:07:54 INFO - rmtree: /builds/slave/test/build 05:07:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:07:57 INFO - Running post-action listener: _resource_record_post_action 05:07:57 INFO - [mozharness: 2016-11-30 13:07:57.169723Z] Finished clobber step (success) 05:07:57 INFO - [mozharness: 2016-11-30 13:07:57.169976Z] Running read-buildbot-config step. 05:07:57 INFO - Running pre-action listener: _resource_record_pre_action 05:07:57 INFO - Running main action method: read_buildbot_config 05:07:57 INFO - Using buildbot properties: 05:07:57 INFO - { 05:07:57 INFO - "project": "", 05:07:57 INFO - "product": "firefox", 05:07:57 INFO - "script_repo_revision": "production", 05:07:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:07:57 INFO - "repository": "", 05:07:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:07:57 INFO - "buildid": "20161130031744", 05:07:57 INFO - "pgo_build": "False", 05:07:57 INFO - "basedir": "/builds/slave/test", 05:07:57 INFO - "buildnumber": 468, 05:07:57 INFO - "slavename": "t-yosemite-r7-0061", 05:07:57 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:07:57 INFO - "platform": "yosemite_r7", 05:07:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:07:57 INFO - "branch": "mozilla-aurora", 05:07:57 INFO - "repo_path": "releases/mozilla-aurora", 05:07:57 INFO - "revision": "d27c9c1d6beb652d9efb39c60efb8806c9b27ae0", 05:07:57 INFO - "stage_platform": "macosx64", 05:07:57 INFO - "builduid": "8ed20d6666f94d7c89a2f9a84369bc73", 05:07:57 INFO - "slavebuilddir": "test" 05:07:57 INFO - } 05:07:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 05:07:57 INFO - Running post-action listener: _resource_record_post_action 05:07:57 INFO - [mozharness: 2016-11-30 13:07:57.175642Z] Finished read-buildbot-config step (success) 05:07:57 INFO - [mozharness: 2016-11-30 13:07:57.175857Z] Running download-and-extract step. 05:07:57 INFO - Running pre-action listener: _resource_record_pre_action 05:07:57 INFO - Running main action method: download_and_extract 05:07:57 INFO - mkdir: /builds/slave/test/build/tests 05:07: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:07:57 INFO - trying https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 05:07:57 INFO - Downloading https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 05:07:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 05:07:58 INFO - Downloaded 1199 bytes. 05:07:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 05:07:58 INFO - Using the following test package requirements: 05:07:58 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:07:58 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 05:07:58 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 05:07:58 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'jsshell-mac.zip'], 05:07:58 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 05:07:58 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 05:07:58 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 05:07:58 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 05:07:58 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 05:07:58 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 05:07:58 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 05:07:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:07:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:07:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:07:58 INFO - Fetch https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:07:59 INFO - Http code: 200 05:07:59 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 05:07:59 INFO - x-amz-cf-id: cUhLdzP6Tk4c0PGGVpUX1nLS3BEk5Mpd0qziaxlpYTIlg56LvLGXlg== 05:07:59 INFO - x-amz-version-id: G7L9Q5OcnBcIt9w4tegcVeJBFykX99hs 05:07:59 INFO - Expected file size: 16285027 05:07:59 INFO - Obtained file size: 16285027 05:08:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:08:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 05:08:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:08:06 INFO - Fetch https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 05:08:06 INFO - Http code: 200 05:08:06 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 05:08:06 INFO - x-amz-cf-id: ie8Yz-P2RDBD8JHUmkiyz2EY_W-6Ybkm00QcrkV4CZ2fz3CSZhda5g== 05:08:06 INFO - x-amz-version-id: G7L9Q5OcnBcIt9w4tegcVeJBFykX99hs 05:08:07 INFO - Expected file size: 16285027 05:08:07 INFO - Obtained file size: 16285027 05:08:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 05:08:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:08:13 INFO - Fetch https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 05:08:14 INFO - Http code: 200 05:08:14 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 05:08:14 INFO - x-amz-cf-id: B_TfhEBHpRPqj4X2PJqPJeAq5-gx3QhYa5qziuZdsdP1ZK-T8mfwmw== 05:08:14 INFO - x-amz-version-id: 7cmeIhi1.6LNoSoSfKaUpM2iEWr0Ycpx 05:08:14 INFO - Expected file size: 14409038 05:08:14 INFO - Obtained file size: 14409038 05:08:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:15 INFO - trying https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:08:15 INFO - Downloading https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 05:08:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 05:08:17 INFO - Downloaded 109713190 bytes. 05:08:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:08:17 INFO - mkdir: /builds/slave/test/properties 05:08:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:17 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:17 INFO - Contents: 05:08:17 INFO - build_url:https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 05:08:19 INFO - Running post-action listener: _resource_record_post_action 05:08:19 INFO - Running post-action listener: set_extra_try_arguments 05:08:19 INFO - [mozharness: 2016-11-30 13:08:19.044406Z] Finished download-and-extract step (success) 05:08:19 INFO - [mozharness: 2016-11-30 13:08:19.044558Z] Running populate-webroot step. 05:08:19 INFO - Running pre-action listener: _resource_record_pre_action 05:08:19 INFO - Running main action method: populate_webroot 05:08:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:19 INFO - '--tppagecycles', 05:08:19 INFO - '7'], 05:08:19 INFO - 'tests': ['tsvgm']}}, 05:08:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:08:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:08:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:08:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['damp', 'tps']}, 05:08:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'tests': ['damp', 'tps']}, 05:08:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['dromaeo_dom']}, 05:08:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['basic_compositor_video']}, 05:08:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:08:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['a11yr', 05:08:19 INFO - 'ts_paint', 05:08:19 INFO - 'tpaint', 05:08:19 INFO - 'sessionrestore', 05:08:19 INFO - 'sessionrestore_no_auto_restore', 05:08:19 INFO - 'tabpaint']}, 05:08:19 INFO - 'other-e10s': {'tests': ['a11yr', 05:08:19 INFO - 'ts_paint', 05:08:19 INFO - 'tpaint', 05:08:19 INFO - 'sessionrestore', 05:08:19 INFO - 'sessionrestore_no_auto_restore', 05:08:19 INFO - 'tabpaint']}, 05:08:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:08:19 INFO - 'ts_paint', 05:08:19 INFO - 'tpaint', 05:08:19 INFO - 'sessionrestore', 05:08:19 INFO - 'sessionrestore_no_auto_restore', 05:08:19 INFO - 'tabpaint']}, 05:08:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:08:19 INFO - 'ts_paint', 05:08:19 INFO - 'tpaint', 05:08:19 INFO - 'sessionrestore', 05:08:19 INFO - 'sessionrestore_no_auto_restore', 05:08:19 INFO - 'tabpaint']}, 05:08:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['a11yr', 05:08:19 INFO - 'ts_paint', 05:08:19 INFO - 'tpaint', 05:08:19 INFO - 'sessionrestore', 05:08:19 INFO - 'sessionrestore_no_auto_restore', 05:08:19 INFO - 'tabpaint']}, 05:08:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['a11yr', 05:08:19 INFO - 'ts_paint', 05:08:19 INFO - 'tpaint', 05:08:19 INFO - 'sessionrestore', 05:08:19 INFO - 'sessionrestore_no_auto_restore', 05:08:19 INFO - 'tabpaint']}, 05:08:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['tsvgx', 05:08:19 INFO - 'tsvgr_opacity', 05:08:19 INFO - 'tart', 05:08:19 INFO - 'tscrollx', 05:08:19 INFO - 'cart']}, 05:08:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:08:19 INFO - 'tsvgr_opacity', 05:08:19 INFO - 'tart', 05:08:19 INFO - 'tscrollx', 05:08:19 INFO - 'cart']}, 05:08:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'talos_options': ['--disable-e10s'], 05:08:19 INFO - 'tests': ['tp5o']}, 05:08:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'tests': ['tp5o']}, 05:08:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'talos_options': ['--disable-e10s', 05:08:19 INFO - '--xperf_path', 05:08:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:19 INFO - 'tests': ['tp5n']}, 05:08:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:19 INFO - 'talos_options': ['--xperf_path', 05:08:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:19 INFO - 'tests': ['tp5n']}}, 05:08:19 INFO - 'talos.zip': {'path': '', 05:08:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:19 INFO - Running post-action listener: _resource_record_post_action 05:08:19 INFO - [mozharness: 2016-11-30 13:08:19.063739Z] Finished populate-webroot step (success) 05:08:19 INFO - [mozharness: 2016-11-30 13:08:19.063871Z] Running create-virtualenv step. 05:08:19 INFO - Running pre-action listener: _resource_record_pre_action 05:08:19 INFO - Running main action method: create_virtualenv 05:08:19 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:19 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 05:08:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:19 INFO - Using real prefix '/tools/python27' 05:08:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:19 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:22 INFO - Installing pip.................done. 05:08:22 INFO - Return code: 0 05:08:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:08:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:08:23 INFO - Reading from file tmpfile_stdout 05:08:23 INFO - Output received: 05:08:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:08:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08: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', 'pip>=1.5'] in /builds/slave/test/build 05:08: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 pip>=1.5 05:08:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:23 INFO - 'HOME': '/Users/cltbld', 05:08:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:23 INFO - 'LOGNAME': 'cltbld', 05:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:23 INFO - 'MOZ_NO_REMOTE': '1', 05:08:23 INFO - 'NO_EM_RESTART': '1', 05:08:23 INFO - 'PAGER': '/bin/cat', 05:08:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:23 INFO - 'PWD': '/builds/slave/test', 05:08:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:23 INFO - 'SHELL': '/bin/bash', 05:08:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:23 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:23 INFO - 'USER': 'cltbld', 05:08:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:23 INFO - 'XPC_FLAGS': '0x0', 05:08:23 INFO - 'XPC_SERVICE_NAME': '0', 05:08:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:23 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 05:08:23 INFO - Cleaning up... 05:08:23 INFO - Return code: 0 05:08:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08: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', '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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:08: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 psutil>=3.1.1 05:08:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:23 INFO - 'HOME': '/Users/cltbld', 05:08:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:23 INFO - 'LOGNAME': 'cltbld', 05:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:23 INFO - 'MOZ_NO_REMOTE': '1', 05:08:23 INFO - 'NO_EM_RESTART': '1', 05:08:23 INFO - 'PAGER': '/bin/cat', 05:08:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:23 INFO - 'PWD': '/builds/slave/test', 05:08:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:23 INFO - 'SHELL': '/bin/bash', 05:08:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:23 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:23 INFO - 'USER': 'cltbld', 05:08:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:23 INFO - 'XPC_FLAGS': '0x0', 05:08:23 INFO - 'XPC_SERVICE_NAME': '0', 05:08:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:23 INFO - Downloading/unpacking psutil>=3.1.1 05:08:23 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 05:08:23 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 05:08:23 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 05:08:23 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 05:08:23 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 05:08:23 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 05:08:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:27 INFO - Installing collected packages: psutil 05:08:27 INFO - Running setup.py install for psutil 05:08:27 INFO - building 'psutil._psutil_osx' extension 05:08:27 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 05:08:27 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 05:08:27 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 05:08:28 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 05:08:28 INFO - building 'psutil._psutil_posix' extension 05:08:28 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 05:08:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:08:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:08:28 INFO - ^ 05:08:28 INFO - 1 warning generated. 05:08:28 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 05:08:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:28 INFO - Successfully installed psutil 05:08:28 INFO - Cleaning up... 05:08:28 INFO - Return code: 0 05:08:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:08:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:28 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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:28 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 05:08:28 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 05:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:28 INFO - 'HOME': '/Users/cltbld', 05:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:28 INFO - 'LOGNAME': 'cltbld', 05:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:28 INFO - 'MOZ_NO_REMOTE': '1', 05:08:28 INFO - 'NO_EM_RESTART': '1', 05:08:28 INFO - 'PAGER': '/bin/cat', 05:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:28 INFO - 'PWD': '/builds/slave/test', 05:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:28 INFO - 'SHELL': '/bin/bash', 05:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:28 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:28 INFO - 'USER': 'cltbld', 05:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:28 INFO - 'XPC_FLAGS': '0x0', 05:08:28 INFO - 'XPC_SERVICE_NAME': '0', 05:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:08:28 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 05:08:28 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 05:08:28 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 05:08:28 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 05:08:28 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 05:08:28 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 05:08:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:08:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:08:31 INFO - Installing collected packages: mozsystemmonitor 05:08:31 INFO - Running setup.py install for mozsystemmonitor 05:08:31 INFO - Successfully installed mozsystemmonitor 05:08:31 INFO - Cleaning up... 05:08:31 INFO - Return code: 0 05:08:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:08:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:31 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:31 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 05:08:31 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 05:08:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:31 INFO - 'HOME': '/Users/cltbld', 05:08:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:31 INFO - 'LOGNAME': 'cltbld', 05:08:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:31 INFO - 'MOZ_NO_REMOTE': '1', 05:08:31 INFO - 'NO_EM_RESTART': '1', 05:08:31 INFO - 'PAGER': '/bin/cat', 05:08:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:31 INFO - 'PWD': '/builds/slave/test', 05:08:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:31 INFO - 'SHELL': '/bin/bash', 05:08:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:31 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:31 INFO - 'USER': 'cltbld', 05:08:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:31 INFO - 'XPC_FLAGS': '0x0', 05:08:31 INFO - 'XPC_SERVICE_NAME': '0', 05:08:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:31 INFO - Downloading/unpacking jsonschema==2.5.1 05:08:31 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 05:08:31 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 05:08:31 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 05:08:31 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 05:08:31 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 05:08:31 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 05:08:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:08:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:08:34 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 05:08:34 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 05:08:34 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 05:08:34 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 05:08:34 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 05:08:34 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 05:08:34 INFO - Downloading functools32-3.2.3-2.tar.gz 05:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:08:34 INFO - warning: no files found matching '*.txt' 05:08:34 INFO - no previously-included directories found matching 'build' 05:08:34 INFO - no previously-included directories found matching 'dist' 05:08:34 INFO - no previously-included directories found matching '.git*' 05:08:34 INFO - Installing collected packages: jsonschema, functools32 05:08:35 INFO - Running setup.py install for functools32 05:08:35 INFO - warning: no files found matching '*.txt' 05:08:35 INFO - no previously-included directories found matching 'build' 05:08:35 INFO - no previously-included directories found matching 'dist' 05:08:35 INFO - no previously-included directories found matching '.git*' 05:08:35 INFO - Successfully installed jsonschema functools32 05:08:35 INFO - Cleaning up... 05:08:35 INFO - Return code: 0 05:08:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:08:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:35 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:08:35 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 functools32==3.2.3-2 05:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:35 INFO - 'HOME': '/Users/cltbld', 05:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:35 INFO - 'LOGNAME': 'cltbld', 05:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:35 INFO - 'MOZ_NO_REMOTE': '1', 05:08:35 INFO - 'NO_EM_RESTART': '1', 05:08:35 INFO - 'PAGER': '/bin/cat', 05:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:35 INFO - 'PWD': '/builds/slave/test', 05:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:35 INFO - 'SHELL': '/bin/bash', 05:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:35 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:35 INFO - 'USER': 'cltbld', 05:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:35 INFO - 'XPC_FLAGS': '0x0', 05:08:35 INFO - 'XPC_SERVICE_NAME': '0', 05:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:08:35 INFO - Cleaning up... 05:08:35 INFO - Return code: 0 05:08:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:08:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:35 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 05:08:35 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 05:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:35 INFO - 'HOME': '/Users/cltbld', 05:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:35 INFO - 'LOGNAME': 'cltbld', 05:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:35 INFO - 'MOZ_NO_REMOTE': '1', 05:08:35 INFO - 'NO_EM_RESTART': '1', 05:08:35 INFO - 'PAGER': '/bin/cat', 05:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:35 INFO - 'PWD': '/builds/slave/test', 05:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:35 INFO - 'SHELL': '/bin/bash', 05:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:35 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:35 INFO - 'USER': 'cltbld', 05:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:35 INFO - 'XPC_FLAGS': '0x0', 05:08:35 INFO - 'XPC_SERVICE_NAME': '0', 05:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:35 INFO - Downloading/unpacking blobuploader==1.2.4 05:08:35 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 05:08:35 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 05:08:35 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 05:08:35 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 05:08:35 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 05:08:35 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 05:08:38 INFO - Downloading blobuploader-1.2.4.tar.gz 05:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:08:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:08:38 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 05:08:38 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 05:08:38 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 05:08:38 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 05:08:38 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 05:08:38 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 05:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:08:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:39 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 05:08:39 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 05:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:39 INFO - Downloading docopt-0.6.1.tar.gz 05:08:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:08:39 INFO - Installing collected packages: blobuploader, requests, docopt 05:08:39 INFO - Running setup.py install for blobuploader 05:08:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:08:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:08:39 INFO - Running setup.py install for requests 05:08:40 INFO - Running setup.py install for docopt 05:08:40 INFO - Successfully installed blobuploader requests docopt 05:08:40 INFO - Cleaning up... 05:08:40 INFO - Return code: 0 05:08:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:40 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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:40 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 05:08:40 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 05:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:40 INFO - 'HOME': '/Users/cltbld', 05:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:40 INFO - 'LOGNAME': 'cltbld', 05:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:40 INFO - 'MOZ_NO_REMOTE': '1', 05:08:40 INFO - 'NO_EM_RESTART': '1', 05:08:40 INFO - 'PAGER': '/bin/cat', 05:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:40 INFO - 'PWD': '/builds/slave/test', 05:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:40 INFO - 'SHELL': '/bin/bash', 05:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:40 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:40 INFO - 'USER': 'cltbld', 05:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:40 INFO - 'XPC_FLAGS': '0x0', 05:08:40 INFO - 'XPC_SERVICE_NAME': '0', 05:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:40 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-OzBJqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:40 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-dUkn9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-rcqSpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-1Bu9WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-XN5s34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-O2YooZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Y1WDxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-IvuMJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-zVgYWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-M3UJXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-vrLn4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:41 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-30EACN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-a9fqmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-7ayF8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-3kbCmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-j6OBv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:42 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-uGrbmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:08:42 INFO - Running setup.py install for manifestparser 05:08:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:08:42 INFO - Running setup.py install for mozcrash 05:08:42 INFO - Running setup.py install for mozdebug 05:08:42 INFO - Running setup.py install for mozdevice 05:08:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozfile 05:08:43 INFO - Running setup.py install for mozhttpd 05:08:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozinfo 05:08:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozInstall 05:08:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozleak 05:08:43 INFO - Running setup.py install for mozlog 05:08:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Running setup.py install for moznetwork 05:08:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Running setup.py install for mozprocess 05:08:44 INFO - Running setup.py install for mozprofile 05:08:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Running setup.py install for mozrunner 05:08:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Running setup.py install for mozscreenshot 05:08:44 INFO - Running setup.py install for moztest 05:08:45 INFO - Running setup.py install for mozversion 05:08:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:08:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:08:45 INFO - Cleaning up... 05:08:45 INFO - Return code: 0 05:08:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:45 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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:45 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 05:08:45 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 05:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:45 INFO - 'HOME': '/Users/cltbld', 05:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:45 INFO - 'LOGNAME': 'cltbld', 05:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:45 INFO - 'MOZ_NO_REMOTE': '1', 05:08:45 INFO - 'NO_EM_RESTART': '1', 05:08:45 INFO - 'PAGER': '/bin/cat', 05:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:45 INFO - 'PWD': '/builds/slave/test', 05:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:45 INFO - 'SHELL': '/bin/bash', 05:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:45 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:45 INFO - 'USER': 'cltbld', 05:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:45 INFO - 'XPC_FLAGS': '0x0', 05:08:45 INFO - 'XPC_SERVICE_NAME': '0', 05:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-pUdIN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:45 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)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-9HHTgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-bi8L6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:45 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)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-k_CbUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:45 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)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-X8xzyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:45 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)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:45 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-ygsYsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:46 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)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-j2N71E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:46 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)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-495g5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:46 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)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-0AQ06d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:46 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)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-734G_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-FsOAjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:46 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)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-2HUsRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:46 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)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-dy0vNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:46 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)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-4nnKcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:46 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-GL1z11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:47 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)) 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-0KR2UW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:47 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)) 05:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:47 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-g7_PlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:47 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)) 05:08:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:47 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)) 05:08:47 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)) 05:08:47 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)) 05:08:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:47 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 05:08:47 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 05:08:47 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 05:08:47 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 05:08:47 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 05:08:47 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 05:08:49 INFO - Downloading blessings-1.6.tar.gz 05:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:08:50 INFO - Installing collected packages: blessings 05:08:50 INFO - Running setup.py install for blessings 05:08:50 INFO - Successfully installed blessings 05:08:50 INFO - Cleaning up... 05:08:50 INFO - Return code: 0 05:08:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:08:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:50 INFO - Reading from file tmpfile_stdout 05:08:50 INFO - Current package versions: 05:08:50 INFO - blessings == 1.6 05:08:50 INFO - blobuploader == 1.2.4 05:08:50 INFO - docopt == 0.6.1 05:08:50 INFO - functools32 == 3.2.3-2 05:08:50 INFO - jsonschema == 2.5.1 05:08:50 INFO - manifestparser == 1.1 05:08:50 INFO - mozInstall == 1.12 05:08:50 INFO - mozcrash == 1.0 05:08:50 INFO - mozdebug == 0.1 05:08:50 INFO - mozdevice == 0.48 05:08:50 INFO - mozfile == 1.2 05:08:50 INFO - mozhttpd == 0.7 05:08:50 INFO - mozinfo == 0.9 05:08:50 INFO - mozleak == 0.1 05:08:50 INFO - mozlog == 3.4 05:08:50 INFO - moznetwork == 0.27 05:08:50 INFO - mozprocess == 0.23 05:08:50 INFO - mozprofile == 0.28 05:08:50 INFO - mozrunner == 6.13 05:08:50 INFO - mozscreenshot == 0.1 05:08:50 INFO - mozsystemmonitor == 0.3 05:08:50 INFO - moztest == 0.7 05:08:50 INFO - mozversion == 1.4 05:08:50 INFO - psutil == 3.1.1 05:08:50 INFO - requests == 1.2.3 05:08:50 INFO - wsgiref == 0.1.2 05:08:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:50 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:50 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:51 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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:51 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 05:08:51 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 05:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:51 INFO - 'HOME': '/Users/cltbld', 05:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:51 INFO - 'LOGNAME': 'cltbld', 05:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:51 INFO - 'MOZ_NO_REMOTE': '1', 05:08:51 INFO - 'NO_EM_RESTART': '1', 05:08:51 INFO - 'PAGER': '/bin/cat', 05:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:51 INFO - 'PWD': '/builds/slave/test', 05:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:51 INFO - 'SHELL': '/bin/bash', 05:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:51 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:51 INFO - 'USER': 'cltbld', 05:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:51 INFO - 'XPC_FLAGS': '0x0', 05:08:51 INFO - 'XPC_SERVICE_NAME': '0', 05:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 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)) 05:08:51 INFO - Cleaning up... 05:08:51 INFO - Return code: 0 05:08:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:08:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:51 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 0x10d3e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d35e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff579599d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, '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.p3ZW4yUq41/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.PipkiCu7rl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 05:08:51 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 05:08:51 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 05:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:08:51 INFO - 'HOME': '/Users/cltbld', 05:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:08:51 INFO - 'LOGNAME': 'cltbld', 05:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:51 INFO - 'MOZ_NO_REMOTE': '1', 05:08:51 INFO - 'NO_EM_RESTART': '1', 05:08:51 INFO - 'PAGER': '/bin/cat', 05:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:51 INFO - 'PWD': '/builds/slave/test', 05:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:08:51 INFO - 'SHELL': '/bin/bash', 05:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:08:51 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:08:51 INFO - 'USER': 'cltbld', 05:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:51 INFO - 'XPC_FLAGS': '0x0', 05:08:51 INFO - 'XPC_SERVICE_NAME': '0', 05:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:08:52 INFO - Cleaning up... 05:08:52 INFO - Return code: 0 05:08:52 INFO - Running post-action listener: _resource_record_post_action 05:08:52 INFO - Running post-action listener: _start_resource_monitoring 05:08:52 INFO - Starting resource monitoring. 05:08:52 INFO - [mozharness: 2016-11-30 13:08:52.222004Z] Finished create-virtualenv step (success) 05:08:52 INFO - [mozharness: 2016-11-30 13:08:52.223041Z] Running install step. 05:08:52 INFO - Running pre-action listener: _resource_record_pre_action 05:08:52 INFO - Running main action method: install 05:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:52 INFO - Reading from file tmpfile_stdout 05:08:52 INFO - Detecting whether we're running mozinstall >=1.0... 05:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:08:52 INFO - Reading from file tmpfile_stdout 05:08:52 INFO - Output received: 05:08:52 INFO - Usage: mozinstall [options] installer 05:08:52 INFO - Options: 05:08:52 INFO - -h, --help show this help message and exit 05:08:52 INFO - -d DEST, --destination=DEST 05:08:52 INFO - Directory to install application into. [default: 05:08:52 INFO - "/builds/slave/test"] 05:08:52 INFO - --app=APP Application being installed. [default: firefox] 05:08:52 INFO - mkdir: /builds/slave/test/build/application 05:08:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:08:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:09:24 INFO - Reading from file tmpfile_stdout 05:09:24 INFO - Output received: 05:09:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:09:24 INFO - Running post-action listener: _resource_record_post_action 05:09:24 INFO - [mozharness: 2016-11-30 13:09:24.394898Z] Finished install step (success) 05:09:24 INFO - [mozharness: 2016-11-30 13:09:24.395077Z] Running run-tests step. 05:09:24 INFO - Running pre-action listener: _resource_record_pre_action 05:09:24 INFO - Running main action method: run_tests 05:09:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:09:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:09:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:09:24 INFO - 2016-11-30 05:09:24.410 screenresolution[1629:7663] starting screenresolution argv=screenresolution get 05:09:24 INFO - 2016-11-30 05:09:24.413 screenresolution[1629:7663] Display 0: 1600x1200x32@60 05:09:24 INFO - 2016-11-30 05:09:24.419 screenresolution[1630:7667] starting screenresolution argv=screenresolution list 05:09:24 INFO - Available Modes on Display 0 05:09:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:09:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:09:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:09:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:09:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:09:24 INFO - Intel Iris: 05:09:24 INFO - Chipset Model: Intel Iris 05:09:24 INFO - Type: GPU 05:09:24 INFO - Bus: Built-In 05:09:24 INFO - VRAM (Dynamic, Max): 1536 MB 05:09:24 INFO - Vendor: Intel (0x8086) 05:09:24 INFO - Device ID: 0x0a2e 05:09:24 INFO - Revision ID: 0x0009 05:09:24 INFO - Displays: 05:09:24 INFO - Display: 05:09:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:09:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:09:24 INFO - Display Serial Number: 1600x1200 60 05:09:24 INFO - Main Display: Yes 05:09:24 INFO - Mirror: Off 05:09:24 INFO - Online: Yes 05:09:24 INFO - Rotation: Supported 05:09:24 INFO - Return code: 0 05:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:24 INFO - Python 2.7.3 05:09:24 INFO - Return code: 0 05:09:24 INFO - grabbing minidump binary from tooltool 05:09:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:24 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 0x10d1fcd30>, '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 0x10d062db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d1f4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:24 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 05:09:24 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 05:09:24 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 05:09:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:24 INFO - Return code: 0 05:09:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:09:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:09:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 05:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0061 --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 05:09:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.p3ZW4yUq41/Render', 05:09:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:09:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:09:24 INFO - 'HOME': '/Users/cltbld', 05:09:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:09:24 INFO - 'LOGNAME': 'cltbld', 05:09:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:24 INFO - 'MOZ_NO_REMOTE': '1', 05:09:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:24 INFO - 'NO_EM_RESTART': '1', 05:09:24 INFO - 'PAGER': '/bin/cat', 05:09:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:24 INFO - 'PWD': '/builds/slave/test', 05:09:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:09:24 INFO - 'SHELL': '/bin/bash', 05:09:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PipkiCu7rl/Listeners', 05:09:24 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 05:09:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:09:24 INFO - 'USER': 'cltbld', 05:09:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:09:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:24 INFO - 'XPC_FLAGS': '0x0', 05:09:24 INFO - 'XPC_SERVICE_NAME': '0', 05:09:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:09:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 05:09:24 INFO - mozversion application_buildid: 20161130031744 05:09:24 INFO - mozversion application_changeset: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 05:09:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:09:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:24 INFO - mozversion application_name: Firefox 05:09:24 INFO - mozversion application_remotingname: firefox-dev 05:09:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:09:24 INFO - mozversion application_vendor: Mozilla 05:09:24 INFO - mozversion application_version: 52.0a2 05:09:24 INFO - mozversion platform_buildid: 20161130031744 05:09:24 INFO - mozversion platform_changeset: d27c9c1d6beb652d9efb39c60efb8806c9b27ae0 05:09:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:09:25 INFO - mozversion platform_version: 52.0a2 05:09:25 INFO - using testdate: 1480511364 05:09:25 INFO - actual date: 1480511364 05:09:25 INFO - starting webserver on 'localhost:49244' 05:09:25 INFO - SUITE-START | Running 2 tests 05:09:25 INFO - TEST-START | dromaeo_css 05:09:25 INFO - operating with platform_type : mac_ 05:09:25 INFO - Initialising browser for dromaeo_css test... 05:09:25 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpxqw2SE/profile http://localhost:49244/getInfo.html) 05:09:28 INFO - PROCESS | 1640 | 2016-11-30 05:09:28.089 plugin-container[1642:7847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 05:09:28 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:28 INFO - PROCESS | 1640 | 2016-11-30 05:09:28.092 plugin-container[1642:7847] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 05:09:28 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:28 INFO - PROCESS | 1640 | 2016-11-30 05:09:28.092 plugin-container[1642:7847] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:09:28 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 05:09:28 INFO - PROCESS | 1640 | colorDepth:24 05:09:28 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 05:09:28 INFO - PROCESS | 1640 | __metrics 05:09:28 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 05:09:28 INFO - TEST-INFO | 1640: exit 0 05:09:28 INFO - Browser initialized. 05:09:28 INFO - Running cycle 1/1 for dromaeo_css test... 05:09:28 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpxqw2SE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:31 INFO - PROCESS | 1643 | 2016-11-30 05:09:31.870 plugin-container[1644:7979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 05:09:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:31 INFO - PROCESS | 1643 | 2016-11-30 05:09:31.871 plugin-container[1644:7979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 05:09:31 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:09:31 INFO - PROCESS | 1643 | 2016-11-30 05:09:31.871 plugin-container[1644:7979] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:09:41 INFO - PROCESS | 1643 | RSS: Main: 229367808 05:09:41 INFO - PROCESS | 1643 | 05:10:31 INFO - PROCESS | 1643 | 1480511431072 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] 05:12:46 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:12:47 INFO - PROCESS | 1643 | RSS: Main: 233869312 05:12:47 INFO - PROCESS | 1643 | 05:15:50 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 05:15:50 INFO - PROCESS | 1643 | RSS: Main: 244195328 05:15:50 INFO - PROCESS | 1643 | 05:18:52 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:18:53 INFO - PROCESS | 1643 | RSS: Main: 247828480 05:18:53 INFO - PROCESS | 1643 | 05:21:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:21:56 INFO - PROCESS | 1643 | RSS: Main: 247140352 05:21:56 INFO - PROCESS | 1643 | 05:24:58 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 05:24:59 INFO - PROCESS | 1643 | RSS: Main: 250150912 05:24:59 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 05:28:02 INFO - PROCESS | 1643 | RSS: Main: 251789312 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | __start_tp_report 05:28:02 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 05:28:02 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 05:28:02 INFO - PROCESS | 1643 | |i|pagename|runs| 05:28:02 INFO - PROCESS | 1643 | |0;dojo.html;3370.9157271068234;3380.1718578948153;3399.9830000849993;3410.266792639582;3413.965860341396;5626.718664066796;5633.042382794919;5675.148727690848;5713.17159011943;5717.885642287162;5664.943350566493;5703.828885133453;5729.598928075036;5741.569382296324;5803.332616749065;5180.3265575475325;5209.765560549784;5275.49882761139;5290.656107353029;5333.093374126388;5263.3683957925105;5274.4461831507715;5332.280142180814;5345.946540534584;5381.139017757159;3908.394198899175;5124.359455068116;5166.844994650167;5219;5287.339082614673;5139.12634852076;5229.50319719626;5233.21501774733;5235.240890070956;5255.159174532095;3971.960280397188;4986;5142.07442660321;5161.66449180802;5195.2986346843445;5669.177969194444;5676.716164191774;5726.0838265877255;5742.655440673573;5743.339515955677;5617.354004289518;5708.600397972144;5937.139114828369;6279.811605651837;6290;6202.100695399142;6266.968665156645;6270.09083682868;6283.246010478726;6297.464715499188;5822.359540450547;5827.79602713903;5846.475299736315;5862;5879.3826648202175;6186.690665466709;6217.440430361289;6250.1874756282305;6269.5611307208055;6320.589161704566;6149.13912052313;6164.907526387108;6170.167027451326;6171.259448866164;6210.099535567318;6248.687565621701;6254.655993920378;6266.342034086447;6281.089242059876;6282.994720844643;6007.8197654070445;6048.092786082123;6136.509079273647;6248.687565621701;6277.309495955441;6121.57491608479;6139.093519790236;6171.660558669227;6208.379162083756;6237.844053898689;4916.188828843201;4934.925976110361;4940.7035577865445;4969.453360130383;4983.8504844854715;3926.9214615707524;4481.932771008437;4486.99042715393;4487.057717879282;4487.730736155841;4468.843590474383;5631.577631677641;5790.073588225863;5843.737031833578;5852.034414321589;6228.47058000066;6245.219347581552;6245.500359971192;6252.843678907972;6256.74973001084;5728.398518155534;5814;5815.534757219412;5874.853128671732;5915;5417.647852889629;5456.263404440349;5470.480304371078;5474.260974768435;5479.671219726829;6146.477549408351;6238.906416403848;6268.153799237045;6316.242050953915;6327.968360158354;6172.105044768394;6176.320604733565;6182.845428864314;6207;6230.190075290365;6387.201599800025;6394.680265986589;6401.711922963571;6415.0377443382;6425.164728585254;6052.757889684363;6118.345080237391;6118.847028824315;6122.032243765785;6161.630302181883;6031.4270144336215;6170.81487555374;6237.282712488122;6238.500919926486;6241.469475094486;4443.866813962419;4902.264660300983;5840.269966254218;6175.9500884848785;6188.938110618837;5977.073553599199;5981.162637230704;6079.483243924316;6154.969225153845;6167.414095660905;4761.952380476151;4920.926186107283;5569.303837020372;5918.940810591839;5966.940330596812;5581.888362232651;6152.384761523812;6152.446279834837;6152.753889844356;6183.258009038765;5156.639035267495;5269.552088072557;6009.45914867664;6011.819645410645;6016.969915150396 05:28:02 INFO - PROCESS | 1643 | |1;ext.html;473.35623551969314;474.99287510687367;482.7448633552763;520.5549255386644;521.2754271562527;38335.233295334074;40329.79835100824;40444.79777601115;42490.78754606222;42562.78718606403;41777.58222417775;42240;42305;42393.364099538456;42429.78785106078;9371.062893710625;9538.952305238465;9543.80912381752;9565.760855978586;9576.329656924017;9428.528573571328;9492.81014379712;9507.57215925285;9516.80966380672;9564;9484.19384352331;9495.192908602778;9523.380980236298;9532.285078619097;9561;9469.10043545862;9506.287028472892;9535.570899309549;9538.52307384628;9557.378770379903;9450.763730906714;9514.38156519824;9521.761955951088;9547.761305967337;9555.856662150074;40139;40228.79885600582;40367.192656147;40424.59575404238;40466.39300410496;3256.8860089896975;3623.7282203834816;3730.9926319893475;3737.206606493554;3947.407888816672;28994.855025724948;29579.260518486994;31005;31268.90558830453;31468.37063258722;3179.77741558091;3184.2357834119643;3191.505316675919;3233.8383080845865;3275.8034517929;392785;396402;397156;398231.0088449568;398465;61455.69272153611;61681.69159154265;61830.381696183365;61853;61979.690101549204;63668;63701.681491592244;63998.0400293996;64296.03555946665;64508.677456612415;16208.351665933467;16224.756628650579;16317.10255935935;16396.180190990402;16645.583860403345;12765.744685106245;12831.10182287231;12890.548830790878;12916.160449570747;12942;16964;17067.317307307567;17322.30710771555;17350.739738903925;17379.652406952042;3041.2244877556523;3224.484082546828;6508.349165083454;6553.574017688835;6619.271880093186;767.5011242692183;828.3911325175991;839.290799274612;932.1424289653535;975.4781192062212;29003.564946525818;29813;29996.700032999826;30630.08109756711;30632;944.5135755086136;1006.4011912911806;1029.7580068683853;1058.1217589400933;1103.4041617526445;15124.319405626773;15309.004914680732;15599.688006239812;15750.291236894367;16026.75959860603;580975;698983;703869;704534;706383;50998.72503187376;57540;57652;58038.7098064507;58094.7095264521;1359.483396309396;1365.5766712319212;1401.985980140206;1408.499982506216;1411.4636438153436;28826.567601485574;29586.556201656564;29672.961446349273;29693.812247510723;30162.24594385158;50695.2395714072;51455.485445146565;51550.22674659958;51706.48293517017;51708;625967;631603;632030;632279;632304;26471.470570588866;26870.462590748466;27171.456570868075;27235.727642724112;27290.863545682143;45170;46691.53308466872;46942;48058.51941480541;48062.759686201345;36875.63124368722;36980.48685374885;38909.80545097256;39014.804925975186;39615.80192099021;35216;37443.68947086839;38377.42433863439;38977.8051109754;39438.605613943495;38233.426498601984;38899.41650875183;38960.02599935024;38968.61031389763;39043.609563904;33424.1643958903;34742.65257347495;34809.10717836487;35314.646853531136;35717.28565428625;19538.609227815647;20928;25868.741312586633;25937.22188334353;26235.212943610957 05:28:02 INFO - PROCESS | 1643 | |2;jquery.html;346.3246668995459;366.9743117981741;377.58654273570437;381.3136354561788;395.18986078539007;18952;19024.524386890334;19180.71228931567;19729.50676233093;19794.010299485006;11319.603813866517;11358;11734.706632334195;11767.176297659167;11789.528418863236;5224.686518808874;5840.153177789218;5903.940960590404;5913.497352725023;5921.082232254007;3783.186614877801;3980.6019398060253;4524.389207457;5780.421957804228;5910.202122713422;3980.5024371953505;6480.675966201671;6548.116004339425;6568.244651865025;6571.7371305147735;5998.97000514999;6010.519158467312;6170.969145154268;6538.724948635018;6553.246376666697;5181.818636347747;5607.962526932531;5774.5091667208335;5813.6221145625395;5815.302163740336;12664.620061398173;13047.286335632416;13107.951363890961;13124.081314308003;13236.933815330958;2136.8290536757177;2136.8824714640687;2183.1376606240447;2382.5459776430303;2845.1080031778843;9490;9937.90062099377;10223.335483193569;10274.691759247235;10599.894001059967;1761.1370428490077;1820.599468117012;1825.972610410845;1839.1447976690904;1843.9078046097775;83817;121135;128549;128918;132103.01845472326;11401.600943966921;11634.76730465403;11764.294142351486;11802.82295765547;11973;14441.49454769099;14863;14872.70254594902;14908.925455372653;14911.925440372728;7433.4053275737815;7786.805329866798;8549.743507694779;8688.91388576428;8697.391182617155;8419.621117049748;8433.493990360597;8494.617742201615;8510;8613.741587752378;4425.867223983286;4513.142502924434;4524.117797029548;4597.103564804831;4609.815607375667;1897.6394485047797;1908.961820763577;1928.6239183359392;1931.2275089964128;1967.7441932548677;765.7894079128173;847.6609356257546;897.8473659477904;933.2394098809449;947.545178314413;16498.34006639745;19672.803271967383;20893.582128357346;20981.950902454817;21362.679559806616;862.2843040276556;909.2998391238709;912.4981260306805;927.6706769097009;932.4312169576552;9378.671746488977;10801.405922674328;10806.62176823823;10857.619983300547;10877;580080;581177;594936;600622;604881;92902.0709792907;106340.46829765956;122049.16926246113;137323;140483.29758351142;956.3544607389983;985.4924713772556;1010.6816352848829;1013.0780989299702;1021.2595867995643;16804.747928780835;17570.38503652366;18603.255869765057;19250.422487325402;19302.903485482482;34712.13219669528;34769.30461390808;34774.782882598985;50111.33188884737;51469.48530514647;76539.23460765544;77622.6118869402;78496.60751696205;84248;84529.15470845459;6912.4815638826285;7727;7951.960240198762;8113.276315723959;8391.035030971516;8073.4348595600095;10529.315594486334;12823.487060517475;12953.676158096123;13051.28217948022;10933.398663073289;11027.338359698444;11108;11347;11460.19778615489;10811.729706757395;10929.726756831144;11151.88848111541;11234.719132021764;11424.200305978502;10057.69826905194;10656.934306569281;11470.770584588094;11557.24877882935;11811;7369.520981136209;7436.73971410998;7623.618819059136;7632;7706.190849960673;9978;10685.626003089854;11223.494942727433;11407.600733974272;11638.243514171552 05:28:02 INFO - PROCESS | 1643 | |3;mootools.html;1385.9653508662282;1425.116427814755;1585.9920700396497;1708.8632909367252;1754.2807448945935;7446.813829654261;7690.923090769091;7926.1281259061525;8439.91560084399;8440.535770532626;7877.251661092201;8172.2236387543235;8195.59022048898;8294.212049855269;8309.210624990632;1721.1136264823563;1800.8919464832152;2206.569718904814;2308.2382813671447;2334.575908695694;2283.212291759349;2287.279506955312;2321.58211521926;2324.9070037198576;2336.3925379401394;2239.731232252132;2261.8982145803398;2263.038208761278;2276.8064714499287;2299.5056062946464;1869.728889311049;2161.373201771484;2250.6173950428465;2252.4706693926914;2258.7628299028693;2001.319551352547;2286;2291.106468477295;2306.1006207579057;2308.3421224950976;8270.751877443627;8279.172082791732;8375.246227839523;8387.03549091856;8433.493990360597;5047.116754567936;5198.506141916512;5203.843884683465;5207.270982062514;5216.504432078947;3293.5718356613725;3341.6366731396247;3484.6515348465205;3562.1628917204694;3574;997.6408492942535;1042.671558459083;1058.5871510111062;1062.3041907550567;1066.3921564708119;10462.581496740122;10761.192910531741;10838.620648277272;10959.945200274027;11003.834942475789;4312.310030395121;4392.692511524227;4461.174682683714;4546.681732278775;4547.431571053618;8542.40203185771;8657.567121644019;8680.956595216983;8681.6527338907;8690.348223883235;5078.009788091361;5086.033653605803;5092.057969275622;5105.821296254688;5106.310648062538;4239.851605193803;4619.607333376701;4737.620990320766;4774.976125119352;4794.736289504111;3640.6177351377723;3669.7064234861145;3698.981505092457;3740.214554943438;3759.0602349412648;1123.8258069999;1141.3836528274805;1143.451143451148;1149.971250718722;1157.791597512439;744.9590272535063;843.1231519219942;882.7572417585087;910.4309806371018;921.3688623293066;5598.972005139948;5701.315842098975;5721.628094173865;5739.913901291484;5860.208871802337;774;849.7875531117221;884.8584226523725;897.7755561109723;919.9770005749909;3814.904627384282;3955.8417663293144;4081.69387295954;4143.56492568276;4151.813168407429;10003.599856005823;10123.443210623487;10201.081902628799;10210.081092701692;10225.284230103971;8303.335733141334;9513.33406661527;9867.16129129032;9871.605135794487;9923.454210018519;7489.325960663457;7747.535147891144;7842.137364889858;7867.409944254204;7875.212478752193;5513.724313784375;5522.30971128609;5571.721413929368;5583.302087239095;5898.587098903035;9215.585298661443;9295.814083718424;9392.859107113261;9400.15398614128;9400.858987115336;8161.551114688749;8694.391392602458;9402.106799854002;9454.480003599592;9503.33476656655;7126.394256488256;7327.963360183165;7484.32641062307;7627.161012288533;7825.491343062683;7735.535867847947;7860;7927.365810735243;7933.722319718782;7958.283754462126;6924.272951340036;6993.370596646327;7144.535605185646;7155.284471552803;7229.7469588564145;6774.830629234111;6901.57280062225;6961.8955715665315;7071.151461824614;7110.644467776694;6693.464522838064;6708.262091170065;6724.361185687352;6780.355866192704;6787.253402125861;4737.502562230916;4784.186688263073;4840.419149702058;4852.980874016355;4891.9755401222765;4706.9529304707885;4758.690685105457;4780.808767649394;4829.806807727792;5033.6476446648385 05:28:02 INFO - PROCESS | 1643 | |4;prototype.html;291.361917400892;298.72218836482074;305.81039755351685;308.6527656386565;310.870988539756;5277.8944421111655;5416.783328666848;5509.696966666832;5516.800255851606;5554.444555544444;4859.441164266111;4899.338589290444;5136.04830164537;5376.838694839152;5403.407473265172;4108.055147316127;4120.835166593332;4131.690123240754;4157.729747566414;4190.3923931029985;3863.748856324344;4099.262132816092;4180.51924028736;4204.768737719424;4211.515675697304;4059.741480141151;4060.4924384451942;4079.2861249281405;4093.0557984957054;4103.343465045593;3556.466530020492;3623.691986181178;3655.976326628548;3737.6075512071247;3935.2881496549094;2673.5321318769334;3392.643772403887;3474.2009337852437;3597.5323207983056;3614.2410093880317;4089.9325505870597;4477.30601756725;5071.8985620287385;5123;5423.5932305076885;4366.6070053695;4436.3123715824095;4564.261443134126;4668.836590719308;4672.602828759559;4777.307290442901;5363.594117735227;5703.287089113861;5725.198472213893;5726.513246374022;3502.5989604158285;3641.217138315275;3724.5344331958504;3732.443276355705;3862.696891612926;5893.232030390908;5953.3748956359395;5953.613015154001;5955.570663040882;5985.9401405986255;3471.6528347165586;3906.4921560196995;3907.5897030811925;3920;3949.222849717581;3832.5784163741964;3968.7023473239046;3970.3647416413237;3998.9402808255836;4033.3949907514107;4098.918021639551;4571.788476053848;4586.990862010352;4628.976855115703;4649.302604609268;5692.715364231772;5766.711664416763;5779.33537643168;5784.71076446176;5801.82594522165;1138.4023387721347;1248.6316536621719;1273.9044571657162;1276.6170148955275;1281.3080936294298;845.8012367093728;857.9528125953133;873.2795443758924;874.4534665833854;876.6674654525086;4409.559044095629;5116.82313067989;5122.538971492583;5124.129692662152;5125.641704948192;5012;5012.699238045729;5103.677826588893;5487.478689524489;5596.664200148004;4539.886502837456;4660.997885454643;4817.108834865491;5082.661975491315;5113;2829.872655730497;2866.6846646868826;2887.63904511936;2924.619799426061;2933.2520207347416;4969.503049695001;4997.3253610762085;5053.797848086036;5106.821261255913;5771.653700777967;4935.7532123393685;5322.467753224647;5654.4062873398525;5732.38859518509;5749.8562535936935;5632.718364081779;5762.1068734346245;5788.652680839163;5806.735373204454;5806.790077194085;4549.5905368517;4736.881577960578;5173.63784535079;5515.7242137893745;5751.9424805751405;4691.06808244847;4958.305837182726;5308.349795606976;5334.9466505336;5543.584231182596;5765.94234057654;5800.128936176619;5814.505767009851;5817.70911454434;5834.766609335579;3207.4386982278475;3672.081979505124;3742.428180164392;3751.1056916257116;3757.9248415030997;4603.124256601359;5193.870153246048;5246.554042906396;5267.552258058108;5503.422140675171;4805.495422980537;5191.871332753553;5574.693391863487;5754.367019627922;5763.625364351304;5523.834284971457;5626.549876009991;5746.367899530965;5775.141305054025;5788.27646544182;4337.7397356158735;4343.630791382641;4930.802767889244;5390.892182156414;5646.2942132233475;4616.769161541976;5711.571632127524;5748.597598168255;5778.306603207642;5823.010088284917;5618.185363122406;5641.8589535261945;5646.894208185564;5662.85842853932;5687.43125687428 05:28:02 INFO - PROCESS | 1643 | |5;yui.html;675.0616642866413;746.8207630168761;757.5681861339035;759.9316061554459;780.3729767421161;14365.42538298468;15113;15126.546203613909;15137;15164.924175379121;13215.074944753871;13234.93382533086;13311.935045196362;13359;13365.599032029055;772.4091070331201;803.9517628942267;821.8438496685641;826.7313123234943;827.8220182660726;618.9102580125879;663.7278715726529;735.4337160386499;745.0426202330007;770.8766597344426;852.1861622150831;864.7449002544264;865.969691060813;867.1458613265928;867.822096470222;671.2414971082694;849.8597731374316;855.1363123245536;858.3090612057291;859.325429537813;622.365187508743;633.6768248193455;643.3405759096917;643.4209211709452;644.1529388853643;12297.44661490235;12356.443960021821;12614;12702.745945081138;12850.485980560767;11723.003544698626;12090.576829810914;12274.51566313058;12292.446839892227;12746.936265318614;11254.155938304579;11461.598844040502;12247.081468889868;12379.814302785466;12816.80774788379;3590.551181102362;3620.837062332201;3631.5823680276576;3646.179609587848;3743.2326373093724;65040;65215.673921630565;67664.66167669179;68523.65738171278;96211;10288.588456461808;10535.315204511682;10635.521401536795;10724.785504290026;10833.404162770965;19287.614247715246;19368.90315548413;19443;19507.8049219506;19572;7602.1257555380735;7644.503107298008;7653.885191722128;7718.189590093071;7784.026996625422;4173.436586060843;4191.497020357577;4317.892052698645;4323.632491238217;4330.0906809569715;3321.3025264694684;3360.983195084009;3453.87911423104;3457.6715212054814;3462.272922686214;621.7699451203084;643.2281262485037;648.3063122458924;655.9147310849654;662.1855118204539;648.9870202595922;649.8570314530796;654.9716944397346;655.5771527364823;659.3801826283375;8565.27195188416;9123.77190570241;9174.724758257264;9206.677766278148;9275.536223188814;599.2180204832648;658.713459645056;667.2926697700453;670.483727529809;674.5446823394287;3277;3842.615738426191;4507.594316511464;4540.750258735735;4542.364069030338;61954;75122.62438687771;111301.44349278364;111717.32424013647;120565.79434205545;80490.19509804944;103860.48069759602;106475.4676226614;107966;109805.45097274463;60114.699426502586;76812.2318776805;76855.23144768593;77695;80343;9377.343585948918;9418.62325506972;9566.1868741155;9893.455859927486;9959.751006224902;50823;51373.7431312856;52242.738786305825;53582;53756;53203.201951971525;72598.259271651;73541.26458735559;76255;77080.2291977073;12353.938230308791;12416.813747793609;12607.117501775154;12671.17637353606;12727.490900363882;14157.221352825694;14164.575062748134;14208.28958552089;14377.353019114375;14557.781633275297;12961.481540738641;13278.933605332299;13356.038199942212;13431.932840335736;13442.632777844212;13021.414036368196;13256.403461844047;13331.266780327174;13414.597562073153;13439.798403023768;12397.93801030989;12553.874461255271;12650.240985540897;12891.617168278102;13473.663158421117;9593.970702003999;11193;11278.718032048937;11414.82877756851;11586.536538538701;7650.732224372343;8230.465019773696;8407;9190;11183.881611838751 05:28:02 INFO - PROCESS | 1643 | __end_tp_report 05:28:02 INFO - PROCESS | 1643 | __start_cc_report 05:28:02 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,378 05:28:02 INFO - PROCESS | 1643 | __end_cc_report 05:28:02 INFO - PROCESS | 1643 | __startTimestamp1480512482488__endTimestamp 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | ------- Summary: start ------- 05:28:02 INFO - PROCESS | 1643 | Number of tests: 6 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5681.89 Median:5862.00 stddev:681.92 (11.6%) stddev-sans-first:659.47 05:28:02 INFO - PROCESS | 1643 | Values: 3370.9 3380.2 3400.0 3410.3 3414.0 5626.7 5633.0 5675.1 5713.2 5717.9 5664.9 5703.8 5729.6 5741.6 5803.3 5180.3 5209.8 5275.5 5290.7 5333.1 5263.4 5274.4 5332.3 5345.9 5381.1 3908.4 5124.4 5166.8 5219.0 5287.3 5139.1 5229.5 5233.2 5235.2 5255.2 3972.0 4986.0 5142.1 5161.7 5195.3 5669.2 5676.7 5726.1 5742.7 5743.3 5617.4 5708.6 5937.1 6279.8 6290.0 6202.1 6267.0 6270.1 6283.2 6297.5 5822.4 5827.8 5846.5 5862.0 5879.4 6186.7 6217.4 6250.2 6269.6 6320.6 6149.1 6164.9 6170.2 6171.3 6210.1 6248.7 6254.7 6266.3 6281.1 6283.0 6007.8 6048.1 6136.5 6248.7 6277.3 6121.6 6139.1 6171.7 6208.4 6237.8 4916.2 4934.9 4940.7 4969.5 4983.9 3926.9 4481.9 4487.0 4487.1 4487.7 4468.8 5631.6 5790.1 5843.7 5852.0 6228.5 6245.2 6245.5 6252.8 6256.7 5728.4 5814.0 5815.5 5874.9 5915.0 5417.6 5456.3 5470.5 5474.3 5479.7 6146.5 6238.9 6268.2 6316.2 6328.0 6172.1 6176.3 6182.8 6207.0 6230.2 6387.2 6394.7 6401.7 6415.0 6425.2 6052.8 6118.3 6118.8 6122.0 6161.6 6031.4 6170.8 6237.3 6238.5 6241.5 4443.9 4902.3 5840.3 6176.0 6188.9 5977.1 5981.2 6079.5 6155.0 6167.4 4762.0 4920.9 5569.3 5918.9 5966.9 5581.9 6152.4 6152.4 6152.8 6183.3 5156.6 5269.6 6009.5 6011.8 6017.0 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:70179.04 Median:398348.00 stddev:156202.37 (39.2%) stddev-sans-first:156552.88 05:28:02 INFO - PROCESS | 1643 | Values: 473.4 475.0 482.7 520.6 521.3 38335.2 40329.8 40444.8 42490.8 42562.8 41777.6 42240.0 42305.0 42393.4 42429.8 9371.1 9539.0 9543.8 9565.8 9576.3 9428.5 9492.8 9507.6 9516.8 9564.0 9484.2 9495.2 9523.4 9532.3 9561.0 9469.1 9506.3 9535.6 9538.5 9557.4 9450.8 9514.4 9521.8 9547.8 9555.9 40139.0 40228.8 40367.2 40424.6 40466.4 3256.9 3623.7 3731.0 3737.2 3947.4 28994.9 29579.3 31005.0 31268.9 31468.4 3179.8 3184.2 3191.5 3233.8 3275.8 392785.0 396402.0 397156.0 398231.0 398465.0 61455.7 61681.7 61830.4 61853.0 61979.7 63668.0 63701.7 63998.0 64296.0 64508.7 16208.4 16224.8 16317.1 16396.2 16645.6 12765.7 12831.1 12890.5 12916.2 12942.0 16964.0 17067.3 17322.3 17350.7 17379.7 3041.2 3224.5 6508.3 6553.6 6619.3 767.5 828.4 839.3 932.1 975.5 29003.6 29813.0 29996.7 30630.1 30632.0 944.5 1006.4 1029.8 1058.1 1103.4 15124.3 15309.0 15599.7 15750.3 16026.8 580975.0 698983.0 703869.0 704534.0 706383.0 50998.7 57540.0 57652.0 58038.7 58094.7 1359.5 1365.6 1402.0 1408.5 1411.5 28826.6 29586.6 29673.0 29693.8 30162.2 50695.2 51455.5 51550.2 51706.5 51708.0 625967.0 631603.0 632030.0 632279.0 632304.0 26471.5 26870.5 27171.5 27235.7 27290.9 45170.0 46691.5 46942.0 48058.5 48062.8 36875.6 36980.5 38909.8 39014.8 39615.8 35216.0 37443.7 38377.4 38977.8 39438.6 38233.4 38899.4 38960.0 38968.6 39043.6 33424.2 34742.7 34809.1 35314.6 35717.3 19538.6 20928.0 25868.7 25937.2 26235.2 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:33668.26 Median:1595.72 stddev:98959.74 (6201.6%) stddev-sans-first:99205.72 05:28:02 INFO - PROCESS | 1643 | Values: 346.3 367.0 377.6 381.3 395.2 18952.0 19024.5 19180.7 19729.5 19794.0 11319.6 11358.0 11734.7 11767.2 11789.5 5224.7 5840.2 5903.9 5913.5 5921.1 3783.2 3980.6 4524.4 5780.4 5910.2 3980.5 6480.7 6548.1 6568.2 6571.7 5999.0 6010.5 6171.0 6538.7 6553.2 5181.8 5608.0 5774.5 5813.6 5815.3 12664.6 13047.3 13108.0 13124.1 13236.9 2136.8 2136.9 2183.1 2382.5 2845.1 9490.0 9937.9 10223.3 10274.7 10599.9 1761.1 1820.6 1826.0 1839.1 1843.9 83817.0 121135.0 128549.0 128918.0 132103.0 11401.6 11634.8 11764.3 11802.8 11973.0 14441.5 14863.0 14872.7 14908.9 14911.9 7433.4 7786.8 8549.7 8688.9 8697.4 8419.6 8433.5 8494.6 8510.0 8613.7 4425.9 4513.1 4524.1 4597.1 4609.8 1897.6 1909.0 1928.6 1931.2 1967.7 765.8 847.7 897.8 933.2 947.5 16498.3 19672.8 20893.6 20982.0 21362.7 862.3 909.3 912.5 927.7 932.4 9378.7 10801.4 10806.6 10857.6 10877.0 580080.0 581177.0 594936.0 600622.0 604881.0 92902.1 106340.5 122049.2 137323.0 140483.3 956.4 985.5 1010.7 1013.1 1021.3 16804.7 17570.4 18603.3 19250.4 19302.9 34712.1 34769.3 34774.8 50111.3 51469.5 76539.2 77622.6 78496.6 84248.0 84529.2 6912.5 7727.0 7952.0 8113.3 8391.0 8073.4 10529.3 12823.5 12953.7 13051.3 10933.4 11027.3 11108.0 11347.0 11460.2 10811.7 10929.7 11151.9 11234.7 11424.2 10057.7 10656.9 11470.8 11557.2 11811.0 7369.5 7436.7 7623.6 7632.0 7706.2 9978.0 10685.6 11223.5 11407.6 11638.2 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5388.14 Median:5201.18 stddev:2944.03 (56.6%) stddev-sans-first:2936.93 05:28:02 INFO - PROCESS | 1643 | Values: 1386.0 1425.1 1586.0 1708.9 1754.3 7446.8 7690.9 7926.1 8439.9 8440.5 7877.3 8172.2 8195.6 8294.2 8309.2 1721.1 1800.9 2206.6 2308.2 2334.6 2283.2 2287.3 2321.6 2324.9 2336.4 2239.7 2261.9 2263.0 2276.8 2299.5 1869.7 2161.4 2250.6 2252.5 2258.8 2001.3 2286.0 2291.1 2306.1 2308.3 8270.8 8279.2 8375.2 8387.0 8433.5 5047.1 5198.5 5203.8 5207.3 5216.5 3293.6 3341.6 3484.7 3562.2 3574.0 997.6 1042.7 1058.6 1062.3 1066.4 10462.6 10761.2 10838.6 10959.9 11003.8 4312.3 4392.7 4461.2 4546.7 4547.4 8542.4 8657.6 8681.0 8681.7 8690.3 5078.0 5086.0 5092.1 5105.8 5106.3 4239.9 4619.6 4737.6 4775.0 4794.7 3640.6 3669.7 3699.0 3740.2 3759.1 1123.8 1141.4 1143.5 1150.0 1157.8 745.0 843.1 882.8 910.4 921.4 5599.0 5701.3 5721.6 5739.9 5860.2 774.0 849.8 884.9 897.8 920.0 3814.9 3955.8 4081.7 4143.6 4151.8 10003.6 10123.4 10201.1 10210.1 10225.3 8303.3 9513.3 9867.2 9871.6 9923.5 7489.3 7747.5 7842.1 7867.4 7875.2 5513.7 5522.3 5571.7 5583.3 5898.6 9215.6 9295.8 9392.9 9400.2 9400.9 8161.6 8694.4 9402.1 9454.5 9503.3 7126.4 7328.0 7484.3 7627.2 7825.5 7735.5 7860.0 7927.4 7933.7 7958.3 6924.3 6993.4 7144.5 7155.3 7229.7 6774.8 6901.6 6961.9 7071.2 7110.6 6693.5 6708.3 6724.4 6780.4 6787.3 4737.5 4784.2 4840.4 4853.0 4892.0 4707.0 4758.7 4780.8 4829.8 5033.6 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4484.39 Median:4983.41 stddev:1383.85 (27.8%) stddev-sans-first:1351.47 05:28:02 INFO - PROCESS | 1643 | Values: 291.4 298.7 305.8 308.7 310.9 5277.9 5416.8 5509.7 5516.8 5554.4 4859.4 4899.3 5136.0 5376.8 5403.4 4108.1 4120.8 4131.7 4157.7 4190.4 3863.7 4099.3 4180.5 4204.8 4211.5 4059.7 4060.5 4079.3 4093.1 4103.3 3556.5 3623.7 3656.0 3737.6 3935.3 2673.5 3392.6 3474.2 3597.5 3614.2 4089.9 4477.3 5071.9 5123.0 5423.6 4366.6 4436.3 4564.3 4668.8 4672.6 4777.3 5363.6 5703.3 5725.2 5726.5 3502.6 3641.2 3724.5 3732.4 3862.7 5893.2 5953.4 5953.6 5955.6 5985.9 3471.7 3906.5 3907.6 3920.0 3949.2 3832.6 3968.7 3970.4 3998.9 4033.4 4098.9 4571.8 4587.0 4629.0 4649.3 5692.7 5766.7 5779.3 5784.7 5801.8 1138.4 1248.6 1273.9 1276.6 1281.3 845.8 858.0 873.3 874.5 876.7 4409.6 5116.8 5122.5 5124.1 5125.6 5012.0 5012.7 5103.7 5487.5 5596.7 4539.9 4661.0 4817.1 5082.7 5113.0 2829.9 2866.7 2887.6 2924.6 2933.3 4969.5 4997.3 5053.8 5106.8 5771.7 4935.8 5322.5 5654.4 5732.4 5749.9 5632.7 5762.1 5788.7 5806.7 5806.8 4549.6 4736.9 5173.6 5515.7 5751.9 4691.1 4958.3 5308.3 5334.9 5543.6 5765.9 5800.1 5814.5 5817.7 5834.8 3207.4 3672.1 3742.4 3751.1 3757.9 4603.1 5193.9 5246.6 5267.6 5503.4 4805.5 5191.9 5574.7 5754.4 5763.6 5523.8 5626.5 5746.4 5775.1 5788.3 4337.7 4343.6 4930.8 5390.9 5646.3 4616.8 5711.6 5748.6 5778.3 5823.0 5618.2 5641.9 5646.9 5662.9 5687.4 05:28:02 INFO - PROCESS | 1643 | 05:28:02 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:19370.75 Median:4541.56 stddev:28085.85 (618.4%) stddev-sans-first:28129.55 05:28:02 INFO - PROCESS | 1643 | Values: 675.1 746.8 757.6 759.9 780.4 14365.4 15113.0 15126.5 15137.0 15164.9 13215.1 13234.9 13311.9 13359.0 13365.6 772.4 804.0 821.8 826.7 827.8 618.9 663.7 735.4 745.0 770.9 852.2 864.7 866.0 867.1 867.8 671.2 849.9 855.1 858.3 859.3 622.4 633.7 643.3 643.4 644.2 12297.4 12356.4 12614.0 12702.7 12850.5 11723.0 12090.6 12274.5 12292.4 12746.9 11254.2 11461.6 12247.1 12379.8 12816.8 3590.6 3620.8 3631.6 3646.2 3743.2 65040.0 65215.7 67664.7 68523.7 96211.0 10288.6 10535.3 10635.5 10724.8 10833.4 19287.6 19368.9 19443.0 19507.8 19572.0 7602.1 7644.5 7653.9 7718.2 7784.0 4173.4 4191.5 4317.9 4323.6 4330.1 3321.3 3361.0 3453.9 3457.7 3462.3 621.8 643.2 648.3 655.9 662.2 649.0 649.9 655.0 655.6 659.4 8565.3 9123.8 9174.7 9206.7 9275.5 599.2 658.7 667.3 670.5 674.5 3277.0 3842.6 4507.6 4540.8 4542.4 61954.0 75122.6 111301.4 111717.3 120565.8 80490.2 103860.5 106475.5 107966.0 109805.5 60114.7 76812.2 76855.2 77695.0 80343.0 9377.3 9418.6 9566.2 9893.5 9959.8 50823.0 51373.7 52242.7 53582.0 53756.0 53203.2 72598.3 73541.3 76255.0 77080.2 12353.9 12416.8 12607.1 12671.2 12727.5 14157.2 14164.6 14208.3 14377.4 14557.8 12961.5 13278.9 13356.0 13431.9 13442.6 13021.4 13256.4 13331.3 13414.6 13439.8 12397.9 12553.9 12650.2 12891.6 13473.7 9594.0 11193.0 11278.7 11414.8 11586.5 7650.7 8230.5 8407.0 9190.0 11183.9 05:28:02 INFO - PROCESS | 1643 | -------- Summary: end -------- 05:28:02 INFO - PROCESS | 1643 | 05:28:03 INFO - TEST-INFO | 1643: exit 0 05:28:03 INFO - TEST-OK | dromaeo_css | took 1118156ms 05:28:03 INFO - TEST-START | kraken 05:28:03 INFO - operating with platform_type : mac_ 05:28:03 INFO - Initialising browser for kraken test... 05:28:03 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpCkLROh/profile http://localhost:49244/getInfo.html) 05:28:06 INFO - PROCESS | 1655 | 2016-11-30 05:28:06.202 plugin-container[1656:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 05:28:06 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:06 INFO - PROCESS | 1655 | 2016-11-30 05:28:06.205 plugin-container[1656:10323] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.CFPasteboardClient' 05:28:06 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:06 INFO - PROCESS | 1655 | 2016-11-30 05:28:06.205 plugin-container[1656:10323] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:06 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 05:28:06 INFO - PROCESS | 1655 | colorDepth:24 05:28:06 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 05:28:06 INFO - PROCESS | 1655 | __metrics 05:28:06 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 05:28:07 INFO - TEST-INFO | 1655: exit 0 05:28:07 INFO - Browser initialized. 05:28:07 INFO - Running cycle 1/1 for kraken test... 05:28:07 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpCkLROh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:09 INFO - PROCESS | 1657 | 2016-11-30 05:28:09.808 plugin-container[1658:10446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 05:28:09 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:09 INFO - PROCESS | 1657 | 2016-11-30 05:28:09.809 plugin-container[1658:10446] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 05:28:09 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:09 INFO - PROCESS | 1657 | 2016-11-30 05:28:09.809 plugin-container[1658:10446] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:19 INFO - PROCESS | 1657 | RSS: Main: 229736448 05:28:19 INFO - PROCESS | 1657 | 05:28:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:23 INFO - PROCESS | 1657 | RSS: Main: 241676288 05:28:23 INFO - PROCESS | 1657 | 05:28:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 05:28:26 INFO - PROCESS | 1657 | RSS: Main: 249356288 05:28:26 INFO - PROCESS | 1657 | 05:28:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 05:28:30 INFO - PROCESS | 1657 | RSS: Main: 253165568 05:28:30 INFO - PROCESS | 1657 | 05:28:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 05:28:33 INFO - PROCESS | 1657 | RSS: Main: 253521920 05:28:33 INFO - PROCESS | 1657 | 05:28:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:35 INFO - PROCESS | 1657 | RSS: Main: 254619648 05:28:35 INFO - PROCESS | 1657 | 05:28:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:39 INFO - PROCESS | 1657 | RSS: Main: 259018752 05:28:39 INFO - PROCESS | 1657 | 05:28:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 05:28:43 INFO - PROCESS | 1657 | RSS: Main: 258265088 05:28:43 INFO - PROCESS | 1657 | 05:28:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 05:28:46 INFO - PROCESS | 1657 | RSS: Main: 258375680 05:28:46 INFO - PROCESS | 1657 | 05:28:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:28:48 INFO - PROCESS | 1657 | RSS: Main: 257273856 05:28:48 INFO - PROCESS | 1657 | 05:28:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:28:51 INFO - PROCESS | 1657 | RSS: Main: 257380352 05:28:51 INFO - PROCESS | 1657 | 05:28:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:28:54 INFO - PROCESS | 1657 | RSS: Main: 257306624 05:28:54 INFO - PROCESS | 1657 | 05:28:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:28:57 INFO - PROCESS | 1657 | RSS: Main: 257560576 05:28:57 INFO - PROCESS | 1657 | 05:29:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:01 INFO - PROCESS | 1657 | RSS: Main: 258183168 05:29:01 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 05:29:03 INFO - PROCESS | 1657 | RSS: Main: 259571712 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | __start_tp_report 05:29:03 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 05:29:03 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 05:29:03 INFO - PROCESS | 1657 | |i|pagename|runs| 05:29:03 INFO - PROCESS | 1657 | |0;ai-astar;108;121;122;120;118;120;119;119;120;122 05:29:03 INFO - PROCESS | 1657 | |1;audio-beat-detection;148;117;132;180;118;114;116;115;145;118 05:29:03 INFO - PROCESS | 1657 | |2;audio-dft;198;198;200;232;227;196;196;196;198;197 05:29:03 INFO - PROCESS | 1657 | |3;audio-fft;84;58;62;87;61;62;61;59;99;87 05:29:03 INFO - PROCESS | 1657 | |4;audio-oscillator;86;72;94;72;69;73;115;100;80;90 05:29:03 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;90;90;89;119;136;84;135;132;92;107 05:29:03 INFO - PROCESS | 1657 | |6;imaging-darkroom;96;96;98;96;96;94;150;126;96;97 05:29:03 INFO - PROCESS | 1657 | |7;imaging-desaturate;90;89;94;87;91;88;92;93;89;87 05:29:03 INFO - PROCESS | 1657 | |8;json-parse-financial;58;56;57;60;60;62;61;89;86;61 05:29:03 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;69;71;73;83;80;75;71;70;71;73 05:29:03 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;85;78;92;76;71;83;106;108;75;101 05:29:03 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;154;141;132;144;208;161;167;165;209;158 05:29:03 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;172;171;178;170;173;175;175;174;172;173 05:29:03 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;69;68;70;71;66;70;70;68;70;69 05:29:03 INFO - PROCESS | 1657 | __end_tp_report 05:29:03 INFO - PROCESS | 1657 | __start_cc_report 05:29:03 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,965 05:29:03 INFO - PROCESS | 1657 | __end_cc_report 05:29:03 INFO - PROCESS | 1657 | __startTimestamp1480512543406__endTimestamp 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | ------- Summary: start ------- 05:29:03 INFO - PROCESS | 1657 | Number of tests: 14 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:118.90 Median:120.00 stddev:4.04 (3.4%) stddev-sans-first:1.36 05:29:03 INFO - PROCESS | 1657 | Values: 108.0 121.0 122.0 120.0 118.0 120.0 119.0 119.0 120.0 122.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:125.00 stddev:21.57 (17.3%) stddev-sans-first:21.90 05:29:03 INFO - PROCESS | 1657 | Values: 148.0 117.0 132.0 180.0 118.0 114.0 116.0 115.0 145.0 118.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:203.80 Median:198.00 stddev:13.65 (6.9%) stddev-sans-first:14.32 05:29:03 INFO - PROCESS | 1657 | Values: 198.0 198.0 200.0 232.0 227.0 196.0 196.0 196.0 198.0 197.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:72.00 Median:73.00 stddev:15.38 (21.1%) stddev-sans-first:15.69 05:29:03 INFO - PROCESS | 1657 | Values: 84.0 58.0 62.0 87.0 61.0 62.0 61.0 59.0 99.0 87.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:76.50 stddev:14.89 (19.5%) stddev-sans-first:15.79 05:29:03 INFO - PROCESS | 1657 | Values: 86.0 72.0 94.0 72.0 69.0 73.0 115.0 100.0 80.0 90.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:107.40 Median:86.50 stddev:21.21 (24.5%) stddev-sans-first:21.54 05:29:03 INFO - PROCESS | 1657 | Values: 90.0 90.0 89.0 119.0 136.0 84.0 135.0 132.0 92.0 107.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:104.50 Median:96.00 stddev:18.57 (19.3%) stddev-sans-first:19.44 05:29:03 INFO - PROCESS | 1657 | Values: 96.0 96.0 98.0 96.0 96.0 94.0 150.0 126.0 96.0 97.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:90.00 Median:90.50 stddev:2.45 (2.7%) stddev-sans-first:2.60 05:29:03 INFO - PROCESS | 1657 | Values: 90.0 89.0 94.0 87.0 91.0 88.0 92.0 93.0 89.0 87.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:61.00 stddev:12.03 (19.7%) stddev-sans-first:12.49 05:29:03 INFO - PROCESS | 1657 | Values: 58.0 56.0 57.0 60.0 60.0 62.0 61.0 89.0 86.0 61.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:73.00 stddev:4.55 (6.2%) stddev-sans-first:4.51 05:29:03 INFO - PROCESS | 1657 | Values: 69.0 71.0 73.0 83.0 80.0 75.0 71.0 70.0 71.0 73.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:77.00 stddev:13.51 (17.5%) stddev-sans-first:14.30 05:29:03 INFO - PROCESS | 1657 | Values: 85.0 78.0 92.0 76.0 71.0 83.0 106.0 108.0 75.0 101.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:163.00 stddev:25.97 (15.9%) stddev-sans-first:27.29 05:29:03 INFO - PROCESS | 1657 | Values: 154.0 141.0 132.0 144.0 208.0 161.0 167.0 165.0 209.0 158.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:173.50 stddev:2.31 (1.3%) stddev-sans-first:2.40 05:29:03 INFO - PROCESS | 1657 | Values: 172.0 171.0 178.0 170.0 173.0 175.0 175.0 174.0 172.0 173.0 05:29:03 INFO - PROCESS | 1657 | 05:29:03 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:1.45 (2.1%) stddev-sans-first:1.54 05:29:03 INFO - PROCESS | 1657 | Values: 69.0 68.0 70.0 71.0 66.0 70.0 70.0 68.0 70.0 69.0 05:29:03 INFO - PROCESS | 1657 | -------- Summary: end -------- 05:29:03 INFO - PROCESS | 1657 | 05:29:04 INFO - TEST-INFO | 1657: exit 0 05:29:04 INFO - TEST-OK | kraken | took 61314ms 05:29:04 INFO - SUITE-END | took 1179s 05:29:04 INFO - Completed test suite (00:19:39) 05:29:04 INFO - javascript benchmark 05:29:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7071.878221529932, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3370.9157271068234, 3380.1718578948153, 3399.9830000849993, 3410.266792639582, 3413.965860341396, 5626.718664066796, 5633.042382794919, 5675.148727690848, 5713.17159011943, 5717.885642287162, 5664.943350566493, 5703.828885133453, 5729.598928075036, 5741.569382296324, 5803.332616749065, 5180.3265575475325, 5209.765560549784, 5275.49882761139, 5290.656107353029, 5333.093374126388, 5263.3683957925105, 5274.4461831507715, 5332.280142180814, 5345.946540534584, 5381.139017757159, 3908.394198899175, 5124.359455068116, 5166.844994650167, 5219.0, 5287.339082614673, 5139.12634852076, 5229.50319719626, 5233.21501774733, 5235.240890070956, 5255.159174532095, 3971.960280397188, 4986.0, 5142.07442660321, 5161.66449180802, 5195.2986346843445, 5669.177969194444, 5676.716164191774, 5726.0838265877255, 5742.655440673573, 5743.339515955677, 5617.354004289518, 5708.600397972144, 5937.139114828369, 6279.811605651837, 6290.0, 6202.100695399142, 6266.968665156645, 6270.09083682868, 6283.246010478726, 6297.464715499188, 5822.359540450547, 5827.79602713903, 5846.475299736315, 5862.0, 5879.3826648202175, 6186.690665466709, 6217.440430361289, 6250.1874756282305, 6269.5611307208055, 6320.589161704566, 6149.13912052313, 6164.907526387108, 6170.167027451326, 6171.259448866164, 6210.099535567318, 6248.687565621701, 6254.655993920378, 6266.342034086447, 6281.089242059876, 6282.994720844643, 6007.8197654070445, 6048.092786082123, 6136.509079273647, 6248.687565621701, 6277.309495955441, 6121.57491608479, 6139.093519790236, 6171.660558669227, 6208.379162083756, 6237.844053898689, 4916.188828843201, 4934.925976110361, 4940.7035577865445, 4969.453360130383, 4983.8504844854715, 3926.9214615707524, 4481.932771008437, 4486.99042715393, 4487.057717879282, 4487.730736155841, 4468.843590474383, 5631.577631677641, 5790.073588225863, 5843.737031833578, 5852.034414321589, 6228.47058000066, 6245.219347581552, 6245.500359971192, 6252.843678907972, 6256.74973001084, 5728.398518155534, 5814.0, 5815.534757219412, 5874.853128671732, 5915.0, 5417.647852889629, 5456.263404440349, 5470.480304371078, 5474.260974768435, 5479.671219726829, 6146.477549408351, 6238.906416403848, 6268.153799237045, 6316.242050953915, 6327.968360158354, 6172.105044768394, 6176.320604733565, 6182.845428864314, 6207.0, 6230.190075290365, 6387.201599800025, 6394.680265986589, 6401.711922963571, 6415.0377443382, 6425.164728585254, 6052.757889684363, 6118.345080237391, 6118.847028824315, 6122.032243765785, 6161.630302181883, 6031.4270144336215, 6170.81487555374, 6237.282712488122, 6238.500919926486, 6241.469475094486, 4443.866813962419, 4902.264660300983, 5840.269966254218, 6175.9500884848785, 6188.938110618837, 5977.073553599199, 5981.162637230704, 6079.483243924316, 6154.969225153845, 6167.414095660905, 4761.952380476151, 4920.926186107283, 5569.303837020372, 5918.940810591839, 5966.940330596812, 5581.888362232651, 6152.384761523812, 6152.446279834837, 6152.753889844356, 6183.258009038765, 5156.639035267495, 5269.552088072557, 6009.45914867664, 6011.819645410645, 6016.969915150396], "value": 5640.300314577349, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.35623551969314, 474.99287510687367, 482.7448633552763, 520.5549255386644, 521.2754271562527, 38335.233295334074, 40329.79835100824, 40444.79777601115, 42490.78754606222, 42562.78718606403, 41777.58222417775, 42240.0, 42305.0, 42393.364099538456, 42429.78785106078, 9371.062893710625, 9538.952305238465, 9543.80912381752, 9565.760855978586, 9576.329656924017, 9428.528573571328, 9492.81014379712, 9507.57215925285, 9516.80966380672, 9564.0, 9484.19384352331, 9495.192908602778, 9523.380980236298, 9532.285078619097, 9561.0, 9469.10043545862, 9506.287028472892, 9535.570899309549, 9538.52307384628, 9557.378770379903, 9450.763730906714, 9514.38156519824, 9521.761955951088, 9547.761305967337, 9555.856662150074, 40139.0, 40228.79885600582, 40367.192656147, 40424.59575404238, 40466.39300410496, 3256.8860089896975, 3623.7282203834816, 3730.9926319893475, 3737.206606493554, 3947.407888816672, 28994.855025724948, 29579.260518486994, 31005.0, 31268.90558830453, 31468.37063258722, 3179.77741558091, 3184.2357834119643, 3191.505316675919, 3233.8383080845865, 3275.8034517929, 392785.0, 396402.0, 397156.0, 398231.0088449568, 398465.0, 61455.69272153611, 61681.69159154265, 61830.381696183365, 61853.0, 61979.690101549204, 63668.0, 63701.681491592244, 63998.0400293996, 64296.03555946665, 64508.677456612415, 16208.351665933467, 16224.756628650579, 16317.10255935935, 16396.180190990402, 16645.583860403345, 12765.744685106245, 12831.10182287231, 12890.548830790878, 12916.160449570747, 12942.0, 16964.0, 17067.317307307567, 17322.30710771555, 17350.739738903925, 17379.652406952042, 3041.2244877556523, 3224.484082546828, 6508.349165083454, 6553.574017688835, 6619.271880093186, 767.5011242692183, 828.3911325175991, 839.290799274612, 932.1424289653535, 975.4781192062212, 29003.564946525818, 29813.0, 29996.700032999826, 30630.08109756711, 30632.0, 944.5135755086136, 1006.4011912911806, 1029.7580068683853, 1058.1217589400933, 1103.4041617526445, 15124.319405626773, 15309.004914680732, 15599.688006239812, 15750.291236894367, 16026.75959860603, 580975.0, 698983.0, 703869.0, 704534.0, 706383.0, 50998.72503187376, 57540.0, 57652.0, 58038.7098064507, 58094.7095264521, 1359.483396309396, 1365.5766712319212, 1401.985980140206, 1408.499982506216, 1411.4636438153436, 28826.567601485574, 29586.556201656564, 29672.961446349273, 29693.812247510723, 30162.24594385158, 50695.2395714072, 51455.485445146565, 51550.22674659958, 51706.48293517017, 51708.0, 625967.0, 631603.0, 632030.0, 632279.0, 632304.0, 26471.470570588866, 26870.462590748466, 27171.456570868075, 27235.727642724112, 27290.863545682143, 45170.0, 46691.53308466872, 46942.0, 48058.51941480541, 48062.759686201345, 36875.63124368722, 36980.48685374885, 38909.80545097256, 39014.804925975186, 39615.80192099021, 35216.0, 37443.68947086839, 38377.42433863439, 38977.8051109754, 39438.605613943495, 38233.426498601984, 38899.41650875183, 38960.02599935024, 38968.61031389763, 39043.609563904, 33424.1643958903, 34742.65257347495, 34809.10717836487, 35314.646853531136, 35717.28565428625, 19538.609227815647, 20928.0, 25868.741312586633, 25937.22188334353, 26235.212943610957], "value": 19641.429050490668, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.3246668995459, 366.9743117981741, 377.58654273570437, 381.3136354561788, 395.18986078539007, 18952.0, 19024.524386890334, 19180.71228931567, 19729.50676233093, 19794.010299485006, 11319.603813866517, 11358.0, 11734.706632334195, 11767.176297659167, 11789.528418863236, 5224.686518808874, 5840.153177789218, 5903.940960590404, 5913.497352725023, 5921.082232254007, 3783.186614877801, 3980.6019398060253, 4524.389207457, 5780.421957804228, 5910.202122713422, 3980.5024371953505, 6480.675966201671, 6548.116004339425, 6568.244651865025, 6571.7371305147735, 5998.97000514999, 6010.519158467312, 6170.969145154268, 6538.724948635018, 6553.246376666697, 5181.818636347747, 5607.962526932531, 5774.5091667208335, 5813.6221145625395, 5815.302163740336, 12664.620061398173, 13047.286335632416, 13107.951363890961, 13124.081314308003, 13236.933815330958, 2136.8290536757177, 2136.8824714640687, 2183.1376606240447, 2382.5459776430303, 2845.1080031778843, 9490.0, 9937.90062099377, 10223.335483193569, 10274.691759247235, 10599.894001059967, 1761.1370428490077, 1820.599468117012, 1825.972610410845, 1839.1447976690904, 1843.9078046097775, 83817.0, 121135.0, 128549.0, 128918.0, 132103.01845472326, 11401.600943966921, 11634.76730465403, 11764.294142351486, 11802.82295765547, 11973.0, 14441.49454769099, 14863.0, 14872.70254594902, 14908.925455372653, 14911.925440372728, 7433.4053275737815, 7786.805329866798, 8549.743507694779, 8688.91388576428, 8697.391182617155, 8419.621117049748, 8433.493990360597, 8494.617742201615, 8510.0, 8613.741587752378, 4425.867223983286, 4513.142502924434, 4524.117797029548, 4597.103564804831, 4609.815607375667, 1897.6394485047797, 1908.961820763577, 1928.6239183359392, 1931.2275089964128, 1967.7441932548677, 765.7894079128173, 847.6609356257546, 897.8473659477904, 933.2394098809449, 947.545178314413, 16498.34006639745, 19672.803271967383, 20893.582128357346, 20981.950902454817, 21362.679559806616, 862.2843040276556, 909.2998391238709, 912.4981260306805, 927.6706769097009, 932.4312169576552, 9378.671746488977, 10801.405922674328, 10806.62176823823, 10857.619983300547, 10877.0, 580080.0, 581177.0, 594936.0, 600622.0, 604881.0, 92902.0709792907, 106340.46829765956, 122049.16926246113, 137323.0, 140483.29758351142, 956.3544607389983, 985.4924713772556, 1010.6816352848829, 1013.0780989299702, 1021.2595867995643, 16804.747928780835, 17570.38503652366, 18603.255869765057, 19250.422487325402, 19302.903485482482, 34712.13219669528, 34769.30461390808, 34774.782882598985, 50111.33188884737, 51469.48530514647, 76539.23460765544, 77622.6118869402, 78496.60751696205, 84248.0, 84529.15470845459, 6912.4815638826285, 7727.0, 7951.960240198762, 8113.276315723959, 8391.035030971516, 8073.4348595600095, 10529.315594486334, 12823.487060517475, 12953.676158096123, 13051.28217948022, 10933.398663073289, 11027.338359698444, 11108.0, 11347.0, 11460.19778615489, 10811.729706757395, 10929.726756831144, 11151.88848111541, 11234.719132021764, 11424.200305978502, 10057.69826905194, 10656.934306569281, 11470.770584588094, 11557.24877882935, 11811.0, 7369.520981136209, 7436.73971410998, 7623.618819059136, 7632.0, 7706.190849960673, 9978.0, 10685.626003089854, 11223.494942727433, 11407.600733974272, 11638.243514171552], "value": 9033.111191299866, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.9653508662282, 1425.116427814755, 1585.9920700396497, 1708.8632909367252, 1754.2807448945935, 7446.813829654261, 7690.923090769091, 7926.1281259061525, 8439.91560084399, 8440.535770532626, 7877.251661092201, 8172.2236387543235, 8195.59022048898, 8294.212049855269, 8309.210624990632, 1721.1136264823563, 1800.8919464832152, 2206.569718904814, 2308.2382813671447, 2334.575908695694, 2283.212291759349, 2287.279506955312, 2321.58211521926, 2324.9070037198576, 2336.3925379401394, 2239.731232252132, 2261.8982145803398, 2263.038208761278, 2276.8064714499287, 2299.5056062946464, 1869.728889311049, 2161.373201771484, 2250.6173950428465, 2252.4706693926914, 2258.7628299028693, 2001.319551352547, 2286.0, 2291.106468477295, 2306.1006207579057, 2308.3421224950976, 8270.751877443627, 8279.172082791732, 8375.246227839523, 8387.03549091856, 8433.493990360597, 5047.116754567936, 5198.506141916512, 5203.843884683465, 5207.270982062514, 5216.504432078947, 3293.5718356613725, 3341.6366731396247, 3484.6515348465205, 3562.1628917204694, 3574.0, 997.6408492942535, 1042.671558459083, 1058.5871510111062, 1062.3041907550567, 1066.3921564708119, 10462.581496740122, 10761.192910531741, 10838.620648277272, 10959.945200274027, 11003.834942475789, 4312.310030395121, 4392.692511524227, 4461.174682683714, 4546.681732278775, 4547.431571053618, 8542.40203185771, 8657.567121644019, 8680.956595216983, 8681.6527338907, 8690.348223883235, 5078.009788091361, 5086.033653605803, 5092.057969275622, 5105.821296254688, 5106.310648062538, 4239.851605193803, 4619.607333376701, 4737.620990320766, 4774.976125119352, 4794.736289504111, 3640.6177351377723, 3669.7064234861145, 3698.981505092457, 3740.214554943438, 3759.0602349412648, 1123.8258069999, 1141.3836528274805, 1143.451143451148, 1149.971250718722, 1157.791597512439, 744.9590272535063, 843.1231519219942, 882.7572417585087, 910.4309806371018, 921.3688623293066, 5598.972005139948, 5701.315842098975, 5721.628094173865, 5739.913901291484, 5860.208871802337, 774.0, 849.7875531117221, 884.8584226523725, 897.7755561109723, 919.9770005749909, 3814.904627384282, 3955.8417663293144, 4081.69387295954, 4143.56492568276, 4151.813168407429, 10003.599856005823, 10123.443210623487, 10201.081902628799, 10210.081092701692, 10225.284230103971, 8303.335733141334, 9513.33406661527, 9867.16129129032, 9871.605135794487, 9923.454210018519, 7489.325960663457, 7747.535147891144, 7842.137364889858, 7867.409944254204, 7875.212478752193, 5513.724313784375, 5522.30971128609, 5571.721413929368, 5583.302087239095, 5898.587098903035, 9215.585298661443, 9295.814083718424, 9392.859107113261, 9400.15398614128, 9400.858987115336, 8161.551114688749, 8694.391392602458, 9402.106799854002, 9454.480003599592, 9503.33476656655, 7126.394256488256, 7327.963360183165, 7484.32641062307, 7627.161012288533, 7825.491343062683, 7735.535867847947, 7860.0, 7927.365810735243, 7933.722319718782, 7958.283754462126, 6924.272951340036, 6993.370596646327, 7144.535605185646, 7155.284471552803, 7229.7469588564145, 6774.830629234111, 6901.57280062225, 6961.8955715665315, 7071.151461824614, 7110.644467776694, 6693.464522838064, 6708.262091170065, 6724.361185687352, 6780.355866192704, 6787.253402125861, 4737.502562230916, 4784.186688263073, 4840.419149702058, 4852.980874016355, 4891.9755401222765, 4706.9529304707885, 4758.690685105457, 4780.808767649394, 4829.806807727792, 5033.6476446648385], "value": 4350.885299388393, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.361917400892, 298.72218836482074, 305.81039755351685, 308.6527656386565, 310.870988539756, 5277.8944421111655, 5416.783328666848, 5509.696966666832, 5516.800255851606, 5554.444555544444, 4859.441164266111, 4899.338589290444, 5136.04830164537, 5376.838694839152, 5403.407473265172, 4108.055147316127, 4120.835166593332, 4131.690123240754, 4157.729747566414, 4190.3923931029985, 3863.748856324344, 4099.262132816092, 4180.51924028736, 4204.768737719424, 4211.515675697304, 4059.741480141151, 4060.4924384451942, 4079.2861249281405, 4093.0557984957054, 4103.343465045593, 3556.466530020492, 3623.691986181178, 3655.976326628548, 3737.6075512071247, 3935.2881496549094, 2673.5321318769334, 3392.643772403887, 3474.2009337852437, 3597.5323207983056, 3614.2410093880317, 4089.9325505870597, 4477.30601756725, 5071.8985620287385, 5123.0, 5423.5932305076885, 4366.6070053695, 4436.3123715824095, 4564.261443134126, 4668.836590719308, 4672.602828759559, 4777.307290442901, 5363.594117735227, 5703.287089113861, 5725.198472213893, 5726.513246374022, 3502.5989604158285, 3641.217138315275, 3724.5344331958504, 3732.443276355705, 3862.696891612926, 5893.232030390908, 5953.3748956359395, 5953.613015154001, 5955.570663040882, 5985.9401405986255, 3471.6528347165586, 3906.4921560196995, 3907.5897030811925, 3920.0, 3949.222849717581, 3832.5784163741964, 3968.7023473239046, 3970.3647416413237, 3998.9402808255836, 4033.3949907514107, 4098.918021639551, 4571.788476053848, 4586.990862010352, 4628.976855115703, 4649.302604609268, 5692.715364231772, 5766.711664416763, 5779.33537643168, 5784.71076446176, 5801.82594522165, 1138.4023387721347, 1248.6316536621719, 1273.9044571657162, 1276.6170148955275, 1281.3080936294298, 845.8012367093728, 857.9528125953133, 873.2795443758924, 874.4534665833854, 876.6674654525086, 4409.559044095629, 5116.82313067989, 5122.538971492583, 5124.129692662152, 5125.641704948192, 5012.0, 5012.699238045729, 5103.677826588893, 5487.478689524489, 5596.664200148004, 4539.886502837456, 4660.997885454643, 4817.108834865491, 5082.661975491315, 5113.0, 2829.872655730497, 2866.6846646868826, 2887.63904511936, 2924.619799426061, 2933.2520207347416, 4969.503049695001, 4997.3253610762085, 5053.797848086036, 5106.821261255913, 5771.653700777967, 4935.7532123393685, 5322.467753224647, 5654.4062873398525, 5732.38859518509, 5749.8562535936935, 5632.718364081779, 5762.1068734346245, 5788.652680839163, 5806.735373204454, 5806.790077194085, 4549.5905368517, 4736.881577960578, 5173.63784535079, 5515.7242137893745, 5751.9424805751405, 4691.06808244847, 4958.305837182726, 5308.349795606976, 5334.9466505336, 5543.584231182596, 5765.94234057654, 5800.128936176619, 5814.505767009851, 5817.70911454434, 5834.766609335579, 3207.4386982278475, 3672.081979505124, 3742.428180164392, 3751.1056916257116, 3757.9248415030997, 4603.124256601359, 5193.870153246048, 5246.554042906396, 5267.552258058108, 5503.422140675171, 4805.495422980537, 5191.871332753553, 5574.693391863487, 5754.367019627922, 5763.625364351304, 5523.834284971457, 5626.549876009991, 5746.367899530965, 5775.141305054025, 5788.27646544182, 4337.7397356158735, 4343.630791382641, 4930.802767889244, 5390.892182156414, 5646.2942132233475, 4616.769161541976, 5711.571632127524, 5748.597598168255, 5778.306603207642, 5823.010088284917, 5618.185363122406, 5641.8589535261945, 5646.894208185564, 5662.85842853932, 5687.43125687428], "value": 4039.652856997427, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.0616642866413, 746.8207630168761, 757.5681861339035, 759.9316061554459, 780.3729767421161, 14365.42538298468, 15113.0, 15126.546203613909, 15137.0, 15164.924175379121, 13215.074944753871, 13234.93382533086, 13311.935045196362, 13359.0, 13365.599032029055, 772.4091070331201, 803.9517628942267, 821.8438496685641, 826.7313123234943, 827.8220182660726, 618.9102580125879, 663.7278715726529, 735.4337160386499, 745.0426202330007, 770.8766597344426, 852.1861622150831, 864.7449002544264, 865.969691060813, 867.1458613265928, 867.822096470222, 671.2414971082694, 849.8597731374316, 855.1363123245536, 858.3090612057291, 859.325429537813, 622.365187508743, 633.6768248193455, 643.3405759096917, 643.4209211709452, 644.1529388853643, 12297.44661490235, 12356.443960021821, 12614.0, 12702.745945081138, 12850.485980560767, 11723.003544698626, 12090.576829810914, 12274.51566313058, 12292.446839892227, 12746.936265318614, 11254.155938304579, 11461.598844040502, 12247.081468889868, 12379.814302785466, 12816.80774788379, 3590.551181102362, 3620.837062332201, 3631.5823680276576, 3646.179609587848, 3743.2326373093724, 65040.0, 65215.673921630565, 67664.66167669179, 68523.65738171278, 96211.0, 10288.588456461808, 10535.315204511682, 10635.521401536795, 10724.785504290026, 10833.404162770965, 19287.614247715246, 19368.90315548413, 19443.0, 19507.8049219506, 19572.0, 7602.1257555380735, 7644.503107298008, 7653.885191722128, 7718.189590093071, 7784.026996625422, 4173.436586060843, 4191.497020357577, 4317.892052698645, 4323.632491238217, 4330.0906809569715, 3321.3025264694684, 3360.983195084009, 3453.87911423104, 3457.6715212054814, 3462.272922686214, 621.7699451203084, 643.2281262485037, 648.3063122458924, 655.9147310849654, 662.1855118204539, 648.9870202595922, 649.8570314530796, 654.9716944397346, 655.5771527364823, 659.3801826283375, 8565.27195188416, 9123.77190570241, 9174.724758257264, 9206.677766278148, 9275.536223188814, 599.2180204832648, 658.713459645056, 667.2926697700453, 670.483727529809, 674.5446823394287, 3277.0, 3842.615738426191, 4507.594316511464, 4540.750258735735, 4542.364069030338, 61954.0, 75122.62438687771, 111301.44349278364, 111717.32424013647, 120565.79434205545, 80490.19509804944, 103860.48069759602, 106475.4676226614, 107966.0, 109805.45097274463, 60114.699426502586, 76812.2318776805, 76855.23144768593, 77695.0, 80343.0, 9377.343585948918, 9418.62325506972, 9566.1868741155, 9893.455859927486, 9959.751006224902, 50823.0, 51373.7431312856, 52242.738786305825, 53582.0, 53756.0, 53203.201951971525, 72598.259271651, 73541.26458735559, 76255.0, 77080.2291977073, 12353.938230308791, 12416.813747793609, 12607.117501775154, 12671.17637353606, 12727.490900363882, 14157.221352825694, 14164.575062748134, 14208.28958552089, 14377.353019114375, 14557.781633275297, 12961.481540738641, 13278.933605332299, 13356.038199942212, 13431.932840335736, 13442.632777844212, 13021.414036368196, 13256.403461844047, 13331.266780327174, 13414.597562073153, 13439.798403023768, 12397.93801030989, 12553.874461255271, 12650.240985540897, 12891.617168278102, 13473.663158421117, 9593.970702003999, 11193.0, 11278.718032048937, 11414.82877756851, 11586.536538538701, 7650.732224372343, 8230.465019773696, 8407.0, 9190.0, 11183.881611838751], "value": 7110.942848231176, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 122.0, 120.0, 118.0, 120.0, 119.0, 119.0, 120.0, 122.0], "value": 118.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 117.0, 132.0, 180.0, 118.0, 114.0, 116.0, 115.0, 145.0, 118.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 200.0, 232.0, 227.0, 196.0, 196.0, 196.0, 198.0, 197.0], "value": 203.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 62.0, 87.0, 61.0, 62.0, 61.0, 59.0, 99.0, 87.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 94.0, 72.0, 69.0, 73.0, 115.0, 100.0, 80.0, 90.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 119.0, 136.0, 84.0, 135.0, 132.0, 92.0, 107.0], "value": 107.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 98.0, 96.0, 96.0, 94.0, 150.0, 126.0, 96.0, 97.0], "value": 104.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 94.0, 87.0, 91.0, 88.0, 92.0, 93.0, 89.0, 87.0], "value": 90.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 57.0, 60.0, 60.0, 62.0, 61.0, 89.0, 86.0, 61.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 73.0, 83.0, 80.0, 75.0, 71.0, 70.0, 71.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 92.0, 76.0, 71.0, 83.0, 106.0, 108.0, 75.0, 101.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 141.0, 132.0, 144.0, 208.0, 161.0, 167.0, 165.0, 209.0, 158.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 171.0, 178.0, 170.0, 173.0, 175.0, 175.0, 174.0, 172.0, 173.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 71.0, 66.0, 70.0, 70.0, 68.0, 70.0, 69.0], "value": 69.1, "unit": "score"}]}]} 05:29:04 INFO - Return code: 0 05:29:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:04 ERROR - # TBPL SUCCESS # 05:29:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:29:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:29:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:29:04 INFO - 2016-11-30 05:29:04.729 screenresolution[1662:10828] starting screenresolution argv=screenresolution get 05:29:04 INFO - 2016-11-30 05:29:04.732 screenresolution[1662:10828] Display 0: 1600x1200x32@60 05:29:04 INFO - 2016-11-30 05:29:04.739 screenresolution[1663:10831] starting screenresolution argv=screenresolution list 05:29:04 INFO - Available Modes on Display 0 05:29:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:29:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:29:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:29:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:29:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:29:04 INFO - Intel Iris: 05:29:04 INFO - Chipset Model: Intel Iris 05:29:04 INFO - Type: GPU 05:29:04 INFO - Bus: Built-In 05:29:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:29:04 INFO - Vendor: Intel (0x8086) 05:29:04 INFO - Device ID: 0x0a2e 05:29:04 INFO - Revision ID: 0x0009 05:29:04 INFO - Displays: 05:29:04 INFO - Display: 05:29:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:29:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:29:04 INFO - Display Serial Number: 1600x1200 60 05:29:04 INFO - Main Display: Yes 05:29:04 INFO - Mirror: Off 05:29:04 INFO - Online: Yes 05:29:04 INFO - Rotation: Supported 05:29:04 INFO - Return code: 0 05:29:04 INFO - Running post-action listener: _resource_record_post_action 05:29:04 INFO - [mozharness: 2016-11-30 13:29:04.983613Z] Finished run-tests step (success) 05:29:04 INFO - Running post-run listener: _resource_record_post_run 05:29:05 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38939136; Write bytes: 659267072; Read time: 383; Write time: 1959 05:29:05 INFO - TinderboxPrint: CPU usage
54.4% 05:29:05 INFO - TinderboxPrint: I/O read bytes / time
38,939,136 / 383 05:29:05 INFO - TinderboxPrint: I/O write bytes / time
659,267,072 / 1,959 05:29:05 INFO - TinderboxPrint: CPU idle
2,213.1 (45.6%) 05:29:05 INFO - TinderboxPrint: CPU system
981.5 (20.2%) 05:29:05 INFO - TinderboxPrint: CPU user
1,655.0 (34.1%) 05:29:05 INFO - TinderboxPrint: Swap in / out
387,846,144 / 0 05:29:05 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 349581824; Write bytes: 345613824; Read time: 28245; Write time: 650 05:29:05 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35449344; Write bytes: 306300928; Read time: 350; Write time: 1280 05:29:05 INFO - Running post-run listener: _upload_blobber_files 05:29:05 INFO - Blob upload gear active. 05:29:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:05 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 05:29:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:29:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:29:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:06 INFO - (blobuploader) - INFO - Done attempting. 05:29:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:29:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:29:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:08 INFO - (blobuploader) - INFO - Done attempting. 05:29:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:10 INFO - (blobuploader) - INFO - Done attempting. 05:29:10 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:10 INFO - Return code: 0 05:29:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee4cf95fdb73afc19e62101d1bd2095580b181e1e0948234aa4fa23592ee5be2d7a04d1024f83434136d8e30dffdf472180a845658c191a205643f587c04e652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf13903db788ba86a6f5360465c04759e218d7d04db436d342fc16a9ca7398236b7bafaaf707b75330862698e4ab2dbef7284e35c3b9abc91bd897651f3bbb74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2c988479d13316fcce5cfc33840a50901a700bcafaa0b55ddad432a9698fe0eb0b3ae1dac801ff919c194a3ee740153a8938426c93e3d4e3e4ab46f9469909e"} 05:29:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:10 INFO - Contents: 05:29:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee4cf95fdb73afc19e62101d1bd2095580b181e1e0948234aa4fa23592ee5be2d7a04d1024f83434136d8e30dffdf472180a845658c191a205643f587c04e652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf13903db788ba86a6f5360465c04759e218d7d04db436d342fc16a9ca7398236b7bafaaf707b75330862698e4ab2dbef7284e35c3b9abc91bd897651f3bbb74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2c988479d13316fcce5cfc33840a50901a700bcafaa0b55ddad432a9698fe0eb0b3ae1dac801ff919c194a3ee740153a8938426c93e3d4e3e4ab46f9469909e"} 05:29:10 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:10 INFO - Copying logs to upload dir... 05:29:10 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.331465 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-30 05:29:10.419816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:10.423667) ========= 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.p3ZW4yUq41/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.PipkiCu7rl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee4cf95fdb73afc19e62101d1bd2095580b181e1e0948234aa4fa23592ee5be2d7a04d1024f83434136d8e30dffdf472180a845658c191a205643f587c04e652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf13903db788ba86a6f5360465c04759e218d7d04db436d342fc16a9ca7398236b7bafaaf707b75330862698e4ab2dbef7284e35c3b9abc91bd897651f3bbb74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2c988479d13316fcce5cfc33840a50901a700bcafaa0b55ddad432a9698fe0eb0b3ae1dac801ff919c194a3ee740153a8938426c93e3d4e3e4ab46f9469909e"} build_url:https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/LuckzbUfRYeMK8iOYeu_jQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee4cf95fdb73afc19e62101d1bd2095580b181e1e0948234aa4fa23592ee5be2d7a04d1024f83434136d8e30dffdf472180a845658c191a205643f587c04e652", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf13903db788ba86a6f5360465c04759e218d7d04db436d342fc16a9ca7398236b7bafaaf707b75330862698e4ab2dbef7284e35c3b9abc91bd897651f3bbb74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2c988479d13316fcce5cfc33840a50901a700bcafaa0b55ddad432a9698fe0eb0b3ae1dac801ff919c194a3ee740153a8938426c93e3d4e3e4ab46f9469909e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:10.459604) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:10.459991) ========= 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.p3ZW4yUq41/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.PipkiCu7rl/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.005093 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 05:29:10.510957) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 05:29:10.511692) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 05:29:10.512049) ========= ========= Total master_lag: 0.20 =========