builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0228 starttime: 1484712551.31 results: success (0) buildid: 20170117175127 builduid: c3936674258c4969b4cb51dd3360d426 revision: d49507cffc8ac291ec912ace8240894b9cd53988 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.307719) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.308232) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.308593) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.335611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.336012) ========= 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.LK6H8chO1N/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.8FCPIsS8bu/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-17 20:09:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.44M=0.001s 2017-01-17 20:09:11 (8.44 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.373648 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.733897) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.734293) ========= 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.LK6H8chO1N/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.8FCPIsS8bu/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.019891 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:11.782897) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-17 20:09:11.783293) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d49507cffc8ac291ec912ace8240894b9cd53988 --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 d49507cffc8ac291ec912ace8240894b9cd53988 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LK6H8chO1N/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.8FCPIsS8bu/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-17 20:09:11,868 truncating revision to first 12 chars 2017-01-17 20:09:11,868 Setting DEBUG logging. 2017-01-17 20:09:11,868 attempt 1/10 2017-01-17 20:09:11,868 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d49507cffc8a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-17 20:09:12,989 unpacking tar archive at: mozilla-aurora-d49507cffc8a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.379430 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-17 20:09:13.189874) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:13.190496) ========= script_repo_revision: d49507cffc8ac291ec912ace8240894b9cd53988 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:13.191265) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:13.191865) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-17 20:09:13.214345) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-17 20:09:13.214736) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.LK6H8chO1N/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.8FCPIsS8bu/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09:13 INFO - MultiFileLogger online at 20170117 20:09:13 in /builds/slave/test 20:09:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:09:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:09:13 INFO - {'append_to_log': False, 20:09:13 INFO - 'base_work_dir': '/builds/slave/test', 20:09:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:09:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:09:13 INFO - 'branch': 'Mozilla-Aurora', 20:09:13 INFO - 'buildbot_json_path': 'buildprops.json', 20:09:13 INFO - 'config_files': ('talos/mac_config.py',), 20:09:13 INFO - 'default_actions': ('clobber', 20:09:13 INFO - 'read-buildbot-config', 20:09:13 INFO - 'download-and-extract', 20:09:13 INFO - 'populate-webroot', 20:09:13 INFO - 'create-virtualenv', 20:09:13 INFO - 'install', 20:09:13 INFO - 'run-tests'), 20:09:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:09:13 INFO - 'download_minidump_stackwalk': True, 20:09:13 INFO - 'download_symbols': 'ondemand', 20:09:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:09:13 INFO - 'tooltool.py': '/tools/tooltool.py', 20:09:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:09:13 INFO - '/tools/misc-python/virtualenv.py')}, 20:09:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:09:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:09:13 INFO - 'installer_path': 'installer.exe', 20:09:13 INFO - 'log_level': 'info', 20:09:13 INFO - 'log_name': 'talos', 20:09:13 INFO - 'log_to_console': True, 20:09:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:09:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:09:13 INFO - 'opt_config_files': (), 20:09:13 INFO - 'pip_index': False, 20:09:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:09:13 INFO - 'cmd': ('bash', 20:09:13 INFO - '-c', 20:09:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:09:13 INFO - 'enabled': True, 20:09:13 INFO - 'halt_on_failure': False, 20:09:13 INFO - 'name': 'check_screen_resolution'},), 20:09:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:09:13 INFO - 'cmd': ('bash', 20:09:13 INFO - '-c', 20:09:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:09:13 INFO - 'enabled': True, 20:09:13 INFO - 'halt_on_failure': False, 20:09:13 INFO - 'name': 'check_screen_resolution'},), 20:09:13 INFO - 'run_cmd_checks_enabled': True, 20:09:13 INFO - 'sps_profile': False, 20:09:13 INFO - 'sps_profile_interval': 0, 20:09:13 INFO - 'suite': 'dromaeojs', 20:09:13 INFO - 'system_bits': '32', 20:09:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:09:13 INFO - 'title': 't-yosemite-r7-0228', 20:09:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:09:13 INFO - 'use_talos_json': True, 20:09:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:09:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:09:13 INFO - 'work_dir': 'build'} 20:09:13 INFO - [mozharness: 2017-01-18 04:09:13.415545Z] Running clobber step. 20:09:13 INFO - Running pre-action listener: _resource_record_pre_action 20:09:13 INFO - Running main action method: clobber 20:09:13 INFO - rmtree: /builds/slave/test/build 20:09:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:09:15 INFO - Running post-action listener: _resource_record_post_action 20:09:15 INFO - [mozharness: 2017-01-18 04:09:15.120904Z] Finished clobber step (success) 20:09:15 INFO - [mozharness: 2017-01-18 04:09:15.121043Z] Running read-buildbot-config step. 20:09:15 INFO - Running pre-action listener: _resource_record_pre_action 20:09:15 INFO - Running main action method: read_buildbot_config 20:09:15 INFO - Using buildbot properties: 20:09:15 INFO - { 20:09:15 INFO - "project": "", 20:09:15 INFO - "product": "firefox", 20:09:15 INFO - "script_repo_revision": "production", 20:09:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 20:09:15 INFO - "repository": "", 20:09:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 20:09:15 INFO - "buildid": "20170117175127", 20:09:15 INFO - "pgo_build": "False", 20:09:15 INFO - "basedir": "/builds/slave/test", 20:09:15 INFO - "buildnumber": 25, 20:09:15 INFO - "slavename": "t-yosemite-r7-0228", 20:09:15 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:09:15 INFO - "platform": "yosemite_r7", 20:09:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:09:15 INFO - "branch": "mozilla-aurora", 20:09:15 INFO - "repo_path": "releases/mozilla-aurora", 20:09:15 INFO - "revision": "d49507cffc8ac291ec912ace8240894b9cd53988", 20:09:15 INFO - "stage_platform": "macosx64", 20:09:15 INFO - "builduid": "c3936674258c4969b4cb51dd3360d426", 20:09:15 INFO - "slavebuilddir": "test" 20:09:15 INFO - } 20:09:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 20:09:15 INFO - Running post-action listener: _resource_record_post_action 20:09:15 INFO - [mozharness: 2017-01-18 04:09:15.124805Z] Finished read-buildbot-config step (success) 20:09:15 INFO - [mozharness: 2017-01-18 04:09:15.124963Z] Running download-and-extract step. 20:09:15 INFO - Running pre-action listener: _resource_record_pre_action 20:09:15 INFO - Running main action method: download_and_extract 20:09:15 INFO - mkdir: /builds/slave/test/build/tests 20:09: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')]} 20:09:15 INFO - trying https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 20:09:15 INFO - Downloading https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/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 20:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/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 20:09:15 INFO - Downloaded 1199 bytes. 20:09:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:09:15 INFO - Using the following test package requirements: 20:09:15 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:09:15 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 20:09:15 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 20:09:15 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'jsshell-mac.zip'], 20:09:15 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 20:09:15 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:09:15 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 20:09:15 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 20:09:15 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 20:09:15 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:09:15 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 20:09:15 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:09:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:09:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:09:15 INFO - Fetch https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:09:16 INFO - Http code: 200 20:09:16 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 20:09:16 INFO - x-amz-cf-id: gP5ZuvWyi0-wAk-_8nl8jkFrCn7H3PMIy1SwnZJoOkHV8wRbu7Zj6Q== 20:09:16 INFO - x-amz-version-id: F8lF1HgoZPKnXDio7WR5rOn2oN3fxHUo 20:09:16 INFO - Expected file size: 16275895 20:09:16 INFO - Obtained file size: 16275895 20:09:23 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 20:09:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:09:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:09:23 INFO - Fetch https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:09:23 INFO - Http code: 200 20:09:23 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 20:09:23 INFO - x-amz-cf-id: cTul6FOfHnJMdFRFXd6sNYSSJaqB68o0CWfaiP5VxeezsQ4XKApfRw== 20:09:23 INFO - x-amz-version-id: F8lF1HgoZPKnXDio7WR5rOn2oN3fxHUo 20:09:24 INFO - Expected file size: 16275895 20:09:24 INFO - Obtained file size: 16275895 20:09:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 20:09:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:09:30 INFO - Fetch https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 20:09:31 INFO - Http code: 200 20:09:31 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 20:09:31 INFO - x-amz-cf-id: yqTIZcf8BhiTYdMwCMak9sQN9cq5fDcsxlU-gPa4t-JjxbEd2kT8Ng== 20:09:31 INFO - x-amz-version-id: HMjYR4dmy_wKnJQ5O_WstfwNS8om3W0r 20:09:31 INFO - Expected file size: 14409038 20:09:31 INFO - Obtained file size: 14409038 20:09: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')]} 20:09:31 INFO - trying https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:09:31 INFO - Downloading https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 20:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/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 20:09:34 INFO - Downloaded 110014675 bytes. 20:09:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:09:34 INFO - mkdir: /builds/slave/test/properties 20:09:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:09:34 INFO - Writing to file /builds/slave/test/properties/build_url 20:09:34 INFO - Contents: 20:09:34 INFO - build_url:https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:09:35 INFO - Running post-action listener: _resource_record_post_action 20:09:35 INFO - Running post-action listener: set_extra_try_arguments 20:09:35 INFO - [mozharness: 2017-01-18 04:09:35.284844Z] Finished download-and-extract step (success) 20:09:35 INFO - [mozharness: 2017-01-18 04:09:35.284989Z] Running populate-webroot step. 20:09:35 INFO - Running pre-action listener: _resource_record_pre_action 20:09:35 INFO - Running main action method: populate_webroot 20:09:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:09:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:09:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:09:35 INFO - '--tppagecycles', 20:09:35 INFO - '7'], 20:09:35 INFO - 'tests': ['tsvgm']}}, 20:09:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:09:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:09:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:09:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:09:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:09:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['damp', 'tps']}, 20:09:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'tests': ['damp', 'tps']}, 20:09:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['dromaeo_dom']}, 20:09:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:09:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['basic_compositor_video']}, 20:09:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:09:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['a11yr', 20:09:35 INFO - 'ts_paint', 20:09:35 INFO - 'tpaint', 20:09:35 INFO - 'sessionrestore', 20:09:35 INFO - 'sessionrestore_no_auto_restore', 20:09:35 INFO - 'tabpaint']}, 20:09:35 INFO - 'other-e10s': {'tests': ['a11yr', 20:09:35 INFO - 'ts_paint', 20:09:35 INFO - 'tpaint', 20:09:35 INFO - 'sessionrestore', 20:09:35 INFO - 'sessionrestore_no_auto_restore', 20:09:35 INFO - 'tabpaint']}, 20:09:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:09:35 INFO - 'ts_paint', 20:09:35 INFO - 'tpaint', 20:09:35 INFO - 'sessionrestore', 20:09:35 INFO - 'sessionrestore_no_auto_restore', 20:09:35 INFO - 'tabpaint']}, 20:09:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:09:35 INFO - 'ts_paint', 20:09:35 INFO - 'tpaint', 20:09:35 INFO - 'sessionrestore', 20:09:35 INFO - 'sessionrestore_no_auto_restore', 20:09:35 INFO - 'tabpaint']}, 20:09:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['a11yr', 20:09:35 INFO - 'ts_paint', 20:09:35 INFO - 'tpaint', 20:09:35 INFO - 'sessionrestore', 20:09:35 INFO - 'sessionrestore_no_auto_restore', 20:09:35 INFO - 'tabpaint']}, 20:09:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['a11yr', 20:09:35 INFO - 'ts_paint', 20:09:35 INFO - 'tpaint', 20:09:35 INFO - 'sessionrestore', 20:09:35 INFO - 'sessionrestore_no_auto_restore', 20:09:35 INFO - 'tabpaint']}, 20:09:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['tsvgx', 20:09:35 INFO - 'tsvgr_opacity', 20:09:35 INFO - 'tart', 20:09:35 INFO - 'tscrollx', 20:09:35 INFO - 'cart']}, 20:09:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:09:35 INFO - 'tsvgr_opacity', 20:09:35 INFO - 'tart', 20:09:35 INFO - 'tscrollx', 20:09:35 INFO - 'cart']}, 20:09:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'talos_options': ['--disable-e10s'], 20:09:35 INFO - 'tests': ['tp5o']}, 20:09:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'tests': ['tp5o']}, 20:09:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'talos_options': ['--disable-e10s', 20:09:35 INFO - '--xperf_path', 20:09:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:35 INFO - 'tests': ['tp5n']}, 20:09:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:09:35 INFO - 'talos_options': ['--xperf_path', 20:09:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:09:35 INFO - 'tests': ['tp5n']}}, 20:09:35 INFO - 'talos.zip': {'path': '', 20:09:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:09:35 INFO - Running post-action listener: _resource_record_post_action 20:09:35 INFO - [mozharness: 2017-01-18 04:09:35.303873Z] Finished populate-webroot step (success) 20:09:35 INFO - [mozharness: 2017-01-18 04:09:35.304003Z] Running create-virtualenv step. 20:09:35 INFO - Running pre-action listener: _resource_record_pre_action 20:09:35 INFO - Running main action method: create_virtualenv 20:09:35 INFO - Creating virtualenv /builds/slave/test/build/venv 20:09:35 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 20:09:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:09:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:09:35 INFO - Using real prefix '/tools/python27' 20:09:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:09:36 INFO - Installing distribute.............................................................................................................................................................................................done. 20:09:38 INFO - Installing pip.................done. 20:09:38 INFO - Return code: 0 20:09:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:09:39 INFO - Reading from file tmpfile_stdout 20:09:39 INFO - Output received: 20:09:39 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) 20:09:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:09:39 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')]} 20:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:39 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')]} 20:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09:39 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 20:09:39 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 20:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:39 INFO - 'HOME': '/Users/cltbld', 20:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:39 INFO - 'LOGNAME': 'cltbld', 20:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:39 INFO - 'MOZ_NO_REMOTE': '1', 20:09:39 INFO - 'NO_EM_RESTART': '1', 20:09:39 INFO - 'PAGER': '/bin/cat', 20:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:39 INFO - 'PWD': '/builds/slave/test', 20:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:39 INFO - 'SHELL': '/bin/bash', 20:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:09:39 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:39 INFO - 'USER': 'cltbld', 20:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:39 INFO - 'XPC_FLAGS': '0x0', 20:09:39 INFO - 'XPC_SERVICE_NAME': '0', 20:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:39 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 20:09:39 INFO - Cleaning up... 20:09:39 INFO - Return code: 0 20:09:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:09:39 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')]} 20:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:39 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')]} 20:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09:39 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 20:09:39 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 20:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:39 INFO - 'HOME': '/Users/cltbld', 20:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:39 INFO - 'LOGNAME': 'cltbld', 20:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:39 INFO - 'MOZ_NO_REMOTE': '1', 20:09:39 INFO - 'NO_EM_RESTART': '1', 20:09:39 INFO - 'PAGER': '/bin/cat', 20:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:39 INFO - 'PWD': '/builds/slave/test', 20:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:39 INFO - 'SHELL': '/bin/bash', 20:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:09:39 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:39 INFO - 'USER': 'cltbld', 20:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:39 INFO - 'XPC_FLAGS': '0x0', 20:09:39 INFO - 'XPC_SERVICE_NAME': '0', 20:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:39 INFO - Downloading/unpacking psutil>=3.1.1 20:09: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 20:09: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 20:09: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 20:09: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 20:09: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 20:09: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 20:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:43 INFO - Installing collected packages: psutil 20:09:43 INFO - Running setup.py install for psutil 20:09:43 INFO - building 'psutil._psutil_osx' extension 20:09:43 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 20:09:43 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 20:09:43 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 20:09:43 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 20:09:43 INFO - building 'psutil._psutil_posix' extension 20:09:43 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 20:09:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:09:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:09:43 INFO - ^ 20:09:43 INFO - 1 warning generated. 20:09:43 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 20:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:09:44 INFO - Successfully installed psutil 20:09:44 INFO - Cleaning up... 20:09:44 INFO - Return code: 0 20:09:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:09:44 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')]} 20:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:44 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')]} 20:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:44 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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09:44 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 20:09:44 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 20:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:44 INFO - 'HOME': '/Users/cltbld', 20:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:44 INFO - 'LOGNAME': 'cltbld', 20:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:44 INFO - 'MOZ_NO_REMOTE': '1', 20:09:44 INFO - 'NO_EM_RESTART': '1', 20:09:44 INFO - 'PAGER': '/bin/cat', 20:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:44 INFO - 'PWD': '/builds/slave/test', 20:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:44 INFO - 'SHELL': '/bin/bash', 20:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:09:44 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:44 INFO - 'USER': 'cltbld', 20:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:44 INFO - 'XPC_FLAGS': '0x0', 20:09:44 INFO - 'XPC_SERVICE_NAME': '0', 20:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:09:44 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 20:09:44 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 20:09:44 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 20:09:44 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 20:09:44 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 20:09:44 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 20:09:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:09:47 INFO - Installing collected packages: mozsystemmonitor 20:09:47 INFO - Running setup.py install for mozsystemmonitor 20:09:47 INFO - Successfully installed mozsystemmonitor 20:09:47 INFO - Cleaning up... 20:09:47 INFO - Return code: 0 20:09:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:09:47 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')]} 20:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:47 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')]} 20:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:47 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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09:47 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 20:09:47 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 20:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:47 INFO - 'HOME': '/Users/cltbld', 20:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:47 INFO - 'LOGNAME': 'cltbld', 20:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:47 INFO - 'MOZ_NO_REMOTE': '1', 20:09:47 INFO - 'NO_EM_RESTART': '1', 20:09:47 INFO - 'PAGER': '/bin/cat', 20:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:47 INFO - 'PWD': '/builds/slave/test', 20:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:47 INFO - 'SHELL': '/bin/bash', 20:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:09:47 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:47 INFO - 'USER': 'cltbld', 20:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:47 INFO - 'XPC_FLAGS': '0x0', 20:09:47 INFO - 'XPC_SERVICE_NAME': '0', 20:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:47 INFO - Downloading/unpacking jsonschema==2.5.1 20:09: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 20:09: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 20:09: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 20:09: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 20:09: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 20:09: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 20:09:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:09:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:09:50 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 20:09:50 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 20:09:50 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 20:09:50 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 20:09:50 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 20:09:50 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 20:09:50 INFO - Downloading functools32-3.2.3-2.tar.gz 20:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:09:50 INFO - warning: no files found matching '*.txt' 20:09:50 INFO - no previously-included directories found matching 'build' 20:09:50 INFO - no previously-included directories found matching 'dist' 20:09:50 INFO - no previously-included directories found matching '.git*' 20:09:50 INFO - Installing collected packages: jsonschema, functools32 20:09:50 INFO - Running setup.py install for functools32 20:09:50 INFO - warning: no files found matching '*.txt' 20:09:50 INFO - no previously-included directories found matching 'build' 20:09:50 INFO - no previously-included directories found matching 'dist' 20:09:50 INFO - no previously-included directories found matching '.git*' 20:09:50 INFO - Successfully installed jsonschema functools32 20:09:50 INFO - Cleaning up... 20:09:50 INFO - Return code: 0 20:09:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:09: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')]} 20:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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')]} 20:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:50 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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09:50 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 20:09:50 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 20:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:50 INFO - 'HOME': '/Users/cltbld', 20:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:50 INFO - 'LOGNAME': 'cltbld', 20:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:50 INFO - 'MOZ_NO_REMOTE': '1', 20:09:50 INFO - 'NO_EM_RESTART': '1', 20:09:50 INFO - 'PAGER': '/bin/cat', 20:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:50 INFO - 'PWD': '/builds/slave/test', 20:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:50 INFO - 'SHELL': '/bin/bash', 20:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:09:50 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:50 INFO - 'USER': 'cltbld', 20:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:50 INFO - 'XPC_FLAGS': '0x0', 20:09:50 INFO - 'XPC_SERVICE_NAME': '0', 20:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:09:51 INFO - Cleaning up... 20:09:51 INFO - Return code: 0 20:09:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:09: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')]} 20:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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')]} 20:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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', '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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:09: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 blobuploader==1.2.4 20:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:51 INFO - 'HOME': '/Users/cltbld', 20:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:51 INFO - 'LOGNAME': 'cltbld', 20:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:51 INFO - 'MOZ_NO_REMOTE': '1', 20:09:51 INFO - 'NO_EM_RESTART': '1', 20:09:51 INFO - 'PAGER': '/bin/cat', 20:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:51 INFO - 'PWD': '/builds/slave/test', 20:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:51 INFO - 'SHELL': '/bin/bash', 20:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:09:51 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:51 INFO - 'USER': 'cltbld', 20:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:51 INFO - 'XPC_FLAGS': '0x0', 20:09:51 INFO - 'XPC_SERVICE_NAME': '0', 20:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:51 INFO - Downloading/unpacking blobuploader==1.2.4 20:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:51 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 20:09:51 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 20:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:54 INFO - Downloading blobuploader-1.2.4.tar.gz 20:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:09:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:09:54 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 20:09:54 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 20:09:54 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 20:09:54 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 20:09:54 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 20:09:54 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 20:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:09:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:09:54 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 20:09:54 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 20:09:54 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 20:09:54 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 20:09:54 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 20:09:54 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 20:09:55 INFO - Downloading docopt-0.6.1.tar.gz 20:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:09:55 INFO - Installing collected packages: blobuploader, requests, docopt 20:09:55 INFO - Running setup.py install for blobuploader 20:09:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:09:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:09:55 INFO - Running setup.py install for requests 20:09:55 INFO - Running setup.py install for docopt 20:09:56 INFO - Successfully installed blobuploader requests docopt 20:09:56 INFO - Cleaning up... 20:09:56 INFO - Return code: 0 20:09:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09:56 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')]} 20:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:56 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')]} 20:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:56 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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:09:56 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 20:09:56 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 20:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:56 INFO - 'HOME': '/Users/cltbld', 20:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:56 INFO - 'LOGNAME': 'cltbld', 20:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:56 INFO - 'MOZ_NO_REMOTE': '1', 20:09:56 INFO - 'NO_EM_RESTART': '1', 20:09:56 INFO - 'PAGER': '/bin/cat', 20:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:56 INFO - 'PWD': '/builds/slave/test', 20:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:56 INFO - 'SHELL': '/bin/bash', 20:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:09:56 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:56 INFO - 'USER': 'cltbld', 20:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:56 INFO - 'XPC_FLAGS': '0x0', 20:09:56 INFO - 'XPC_SERVICE_NAME': '0', 20:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:09:56 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-TUygKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:09:56 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WKZzkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:09:56 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Pbj5L8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:09:56 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-4WEl9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:09:56 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-btZxVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:09:56 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-3P6tDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Kin2b3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RfXi4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-deymG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yXBUvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-v9n_Mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-zF843Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Imae2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-E1q1Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:57 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-vgm99A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:09:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-xUTVpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:09:58 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-BLrERs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:09:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:09:58 INFO - Running setup.py install for manifestparser 20:09:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:09:58 INFO - Running setup.py install for mozcrash 20:09:58 INFO - Running setup.py install for mozdebug 20:09:58 INFO - Running setup.py install for mozdevice 20:09:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:09:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:09:58 INFO - Running setup.py install for mozfile 20:09:58 INFO - Running setup.py install for mozhttpd 20:09:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Running setup.py install for mozinfo 20:09:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Running setup.py install for mozInstall 20:09:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Running setup.py install for mozleak 20:09:59 INFO - Running setup.py install for mozlog 20:09:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Running setup.py install for moznetwork 20:09:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:09:59 INFO - Running setup.py install for mozprocess 20:10:00 INFO - Running setup.py install for mozprofile 20:10:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozrunner 20:10:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Running setup.py install for mozscreenshot 20:10:00 INFO - Running setup.py install for moztest 20:10:00 INFO - Running setup.py install for mozversion 20:10:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:10:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:10:00 INFO - Cleaning up... 20:10:00 INFO - Return code: 0 20:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:00 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')]} 20:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:00 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')]} 20:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:00 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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:10:00 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 20:10:00 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 20:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:00 INFO - 'HOME': '/Users/cltbld', 20:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:00 INFO - 'LOGNAME': 'cltbld', 20:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:00 INFO - 'MOZ_NO_REMOTE': '1', 20:10:00 INFO - 'NO_EM_RESTART': '1', 20:10:00 INFO - 'PAGER': '/bin/cat', 20:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:00 INFO - 'PWD': '/builds/slave/test', 20:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:00 INFO - 'SHELL': '/bin/bash', 20:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:10:00 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:00 INFO - 'USER': 'cltbld', 20:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:00 INFO - 'XPC_FLAGS': '0x0', 20:10:00 INFO - 'XPC_SERVICE_NAME': '0', 20:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-SkXs5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ENUlyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Yk8GrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-yJrnZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-NmjJwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-ue5VJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-PaQZ5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-U3P6Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:10:01 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)) 20:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:10:01 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-RANl1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-Ben1a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-WxJ88j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-GI34GJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-JaWREE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-M2apD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-tipdix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:10:02 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)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-4tAGSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:10:02 INFO - Running setup.py (path:/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/pip-JmHZj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 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)) 20:10:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:10:05 INFO - Downloading blessings-1.6.tar.gz 20:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:10:05 INFO - Installing collected packages: blessings 20:10:05 INFO - Running setup.py install for blessings 20:10:05 INFO - Successfully installed blessings 20:10:05 INFO - Cleaning up... 20:10:05 INFO - Return code: 0 20:10:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:10:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:06 INFO - Reading from file tmpfile_stdout 20:10:06 INFO - Current package versions: 20:10:06 INFO - blessings == 1.6 20:10:06 INFO - blobuploader == 1.2.4 20:10:06 INFO - docopt == 0.6.1 20:10:06 INFO - functools32 == 3.2.3-2 20:10:06 INFO - jsonschema == 2.5.1 20:10:06 INFO - manifestparser == 1.1 20:10:06 INFO - mozInstall == 1.12 20:10:06 INFO - mozcrash == 1.0 20:10:06 INFO - mozdebug == 0.1 20:10:06 INFO - mozdevice == 0.48 20:10:06 INFO - mozfile == 1.2 20:10:06 INFO - mozhttpd == 0.7 20:10:06 INFO - mozinfo == 0.9 20:10:06 INFO - mozleak == 0.1 20:10:06 INFO - mozlog == 3.4 20:10:06 INFO - moznetwork == 0.27 20:10:06 INFO - mozprocess == 0.23 20:10:06 INFO - mozprofile == 0.28 20:10:06 INFO - mozrunner == 6.13 20:10:06 INFO - mozscreenshot == 0.1 20:10:06 INFO - mozsystemmonitor == 0.3 20:10:06 INFO - moztest == 0.8 20:10:06 INFO - mozversion == 1.4 20:10:06 INFO - psutil == 3.1.1 20:10:06 INFO - requests == 1.2.3 20:10:06 INFO - wsgiref == 0.1.2 20:10:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:10:06 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')]} 20:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:06 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')]} 20:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:06 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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:10:06 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 20:10:06 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 20:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:06 INFO - 'HOME': '/Users/cltbld', 20:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:06 INFO - 'LOGNAME': 'cltbld', 20:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:06 INFO - 'MOZ_NO_REMOTE': '1', 20:10:06 INFO - 'NO_EM_RESTART': '1', 20:10:06 INFO - 'PAGER': '/bin/cat', 20:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:06 INFO - 'PWD': '/builds/slave/test', 20:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:06 INFO - 'SHELL': '/bin/bash', 20:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:10:06 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:06 INFO - 'USER': 'cltbld', 20:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:06 INFO - 'XPC_FLAGS': '0x0', 20:10:06 INFO - 'XPC_SERVICE_NAME': '0', 20:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 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)) 20:10:06 INFO - Cleaning up... 20:10:06 INFO - Return code: 0 20:10:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:10:06 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')]} 20:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:06 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')]} 20:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:10:06 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 0x10f6cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f641570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0a9cc87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, '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.LK6H8chO1N/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.8FCPIsS8bu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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 20:10:06 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 20:10:06 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 20:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:06 INFO - 'HOME': '/Users/cltbld', 20:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:06 INFO - 'LOGNAME': 'cltbld', 20:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:06 INFO - 'MOZ_NO_REMOTE': '1', 20:10:06 INFO - 'NO_EM_RESTART': '1', 20:10:06 INFO - 'PAGER': '/bin/cat', 20:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:06 INFO - 'PWD': '/builds/slave/test', 20:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:06 INFO - 'SHELL': '/bin/bash', 20:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:10:06 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:06 INFO - 'USER': 'cltbld', 20:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:06 INFO - 'XPC_FLAGS': '0x0', 20:10:06 INFO - 'XPC_SERVICE_NAME': '0', 20:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:10:06 INFO - Cleaning up... 20:10:06 INFO - Return code: 0 20:10:06 INFO - Running post-action listener: _resource_record_post_action 20:10:06 INFO - Running post-action listener: _start_resource_monitoring 20:10:06 INFO - Starting resource monitoring. 20:10:06 INFO - [mozharness: 2017-01-18 04:10:06.711039Z] Finished create-virtualenv step (success) 20:10:06 INFO - [mozharness: 2017-01-18 04:10:06.712005Z] Running install step. 20:10:06 INFO - Running pre-action listener: _resource_record_pre_action 20:10:06 INFO - Running main action method: install 20:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:10:06 INFO - Reading from file tmpfile_stdout 20:10:06 INFO - Detecting whether we're running mozinstall >=1.0... 20:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:10:07 INFO - Reading from file tmpfile_stdout 20:10:07 INFO - Output received: 20:10:07 INFO - Usage: mozinstall [options] installer 20:10:07 INFO - Options: 20:10:07 INFO - -h, --help show this help message and exit 20:10:07 INFO - -d DEST, --destination=DEST 20:10:07 INFO - Directory to install application into. [default: 20:10:07 INFO - "/builds/slave/test"] 20:10:07 INFO - --app=APP Application being installed. [default: firefox] 20:10:07 INFO - mkdir: /builds/slave/test/build/application 20:10:07 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'] 20:10:07 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 20:10:38 INFO - Reading from file tmpfile_stdout 20:10:38 INFO - Output received: 20:10:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:10:38 INFO - Running post-action listener: _resource_record_post_action 20:10:38 INFO - [mozharness: 2017-01-18 04:10:38.028783Z] Finished install step (success) 20:10:38 INFO - [mozharness: 2017-01-18 04:10:38.028949Z] Running run-tests step. 20:10:38 INFO - Running pre-action listener: _resource_record_pre_action 20:10:38 INFO - Running main action method: run_tests 20:10:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:10:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:10:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:10:38 INFO - 2017-01-17 20:10:38.043 screenresolution[1894:16098] starting screenresolution argv=screenresolution get 20:10:38 INFO - 2017-01-17 20:10:38.045 screenresolution[1894:16098] Display 0: 1600x1200x32@60 20:10:38 INFO - 2017-01-17 20:10:38.051 screenresolution[1895:16100] starting screenresolution argv=screenresolution list 20:10:38 INFO - Available Modes on Display 0 20:10:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:10:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:10:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:10:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:10:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:10:38 INFO - Intel Iris: 20:10:38 INFO - Chipset Model: Intel Iris 20:10:38 INFO - Type: GPU 20:10:38 INFO - Bus: Built-In 20:10:38 INFO - VRAM (Dynamic, Max): 1536 MB 20:10:38 INFO - Vendor: Intel (0x8086) 20:10:38 INFO - Device ID: 0x0a2e 20:10:38 INFO - Revision ID: 0x0009 20:10:38 INFO - Displays: 20:10:38 INFO - Display: 20:10:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:10:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:10:38 INFO - Display Serial Number: 1600x1200 60 20:10:38 INFO - Main Display: Yes 20:10:38 INFO - Mirror: Off 20:10:38 INFO - Online: Yes 20:10:38 INFO - Rotation: Supported 20:10:38 INFO - Return code: 0 20:10:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:10:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:10:38 INFO - Python 2.7.3 20:10:38 INFO - Return code: 0 20:10:38 INFO - grabbing minidump binary from tooltool 20:10:38 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')]} 20:10:38 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 0x10f4dfd30>, '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 0x10f345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f4d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:10:38 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 20:10:38 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 20:10:38 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 20:10:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:10:38 INFO - Return code: 0 20:10:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:10:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:10:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:10:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:10:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:10:38 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:10:38 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0228 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LK6H8chO1N/Render', 20:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:10:38 INFO - 'HOME': '/Users/cltbld', 20:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:10:38 INFO - 'LOGNAME': 'cltbld', 20:10:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:10:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:10:38 INFO - 'MOZ_NO_REMOTE': '1', 20:10:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:10:38 INFO - 'NO_EM_RESTART': '1', 20:10:38 INFO - 'PAGER': '/bin/cat', 20:10:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:10:38 INFO - 'PWD': '/builds/slave/test', 20:10:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:10:38 INFO - 'SHELL': '/bin/bash', 20:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8FCPIsS8bu/Listeners', 20:10:38 INFO - 'TMPDIR': '/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/', 20:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:10:38 INFO - 'USER': 'cltbld', 20:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:10:38 INFO - 'XPC_FLAGS': '0x0', 20:10:38 INFO - 'XPC_SERVICE_NAME': '0', 20:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:10:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0228', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:10:38 INFO - mozversion application_buildid: 20170117175127 20:10:38 INFO - mozversion application_changeset: d49507cffc8ac291ec912ace8240894b9cd53988 20:10:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:10:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:10:38 INFO - mozversion application_name: Firefox 20:10:38 INFO - mozversion application_remotingname: firefox-dev 20:10:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:10:38 INFO - mozversion application_vendor: Mozilla 20:10:38 INFO - mozversion application_version: 52.0a2 20:10:38 INFO - mozversion platform_buildid: 20170117175127 20:10:38 INFO - mozversion platform_changeset: d49507cffc8ac291ec912ace8240894b9cd53988 20:10:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:10:38 INFO - mozversion platform_version: 52.0a2 20:10:38 INFO - using testdate: 1484712638 20:10:38 INFO - actual date: 1484712638 20:10:38 INFO - starting webserver on 'localhost:53701' 20:10:38 INFO - SUITE-START | Running 2 tests 20:10:38 INFO - TEST-START | dromaeo_css 20:10:38 INFO - operating with platform_type : mac_ 20:10:38 INFO - Initialising browser for dromaeo_css test... 20:10:38 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmp_bY57v/profile http://localhost:53701/getInfo.html) 20:10:41 INFO - PROCESS | 1905 | __metrics Screen width/height:1600/1200 20:10:41 INFO - PROCESS | 1905 | colorDepth:24 20:10:41 INFO - PROCESS | 1905 | Browser inner width/height: 1024/689 20:10:41 INFO - PROCESS | 1905 | __metrics 20:10:41 INFO - PROCESS | 1905 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:10:41 INFO - PROCESS | 1905 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:10:42 INFO - TEST-INFO | 1905: exit 0 20:10:42 INFO - Browser initialized. 20:10:42 INFO - Running cycle 1/1 for dromaeo_css test... 20:10:42 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmp_bY57v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:10:54 INFO - PROCESS | 1907 | RSS: Main: 238796800 20:10:54 INFO - PROCESS | 1907 | 20:13:57 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53701/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53701/tests/dromaeo/cssquery-ext.html) 20:13:59 INFO - PROCESS | 1907 | RSS: Main: 385372160 20:13:59 INFO - PROCESS | 1907 | 20:17:02 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53701/tests/dromaeo/cssquery-ext.html (next: http://localhost:53701/tests/dromaeo/cssquery-jquery.html) 20:17:03 INFO - PROCESS | 1907 | RSS: Main: 364826624 20:17:03 INFO - PROCESS | 1907 | 20:20:05 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53701/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53701/tests/dromaeo/cssquery-mootools.html) 20:20:06 INFO - PROCESS | 1907 | RSS: Main: 338001920 20:20:06 INFO - PROCESS | 1907 | 20:23:08 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53701/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53701/tests/dromaeo/cssquery-prototype.html) 20:23:09 INFO - PROCESS | 1907 | RSS: Main: 330506240 20:23:09 INFO - PROCESS | 1907 | 20:26:12 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53701/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53701/tests/dromaeo/cssquery-yui.html) 20:26:13 INFO - PROCESS | 1907 | RSS: Main: 321212416 20:26:13 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | Cycle 1(1): loaded http://localhost:53701/tests/dromaeo/cssquery-yui.html (next: http://localhost:53701/tests/dromaeo/cssquery-dojo.html) 20:29:16 INFO - PROCESS | 1907 | RSS: Main: 391090176 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | __start_tp_report 20:29:16 INFO - PROCESS | 1907 | _x_x_mozilla_page_load 20:29:16 INFO - PROCESS | 1907 | _x_x_mozilla_page_load_details 20:29:16 INFO - PROCESS | 1907 | |i|pagename|runs| 20:29:16 INFO - PROCESS | 1907 | |0;dojo.html;3313.8840140595084;3323.01970918579;3342.4484959981605;3360.8823691170815;3388.322335532894;5616.550675945925;5656.066748986423;5676.631018983763;5686.061799803033;5735.139729040641;5604.579656525756;5662.858428539278;5665.5467562594995;5672.035753921833;5676.233708449358;5174.793008279665;5181.0674078665825;5188.066148093341;5207.869803254911;5213.713245771481;5109.386873575176;5118.872028199287;5136.691798492084;5200.92198617021;5272.499112584317;5130.281760553526;5214.817481388152;5219.399769026553;5224.216367544861;5244.370675518943;5193.350831146106;5203.843884683465;5222.817201397971;5236.240745091978;5256.448072952361;5107.668001579886;5205.323307969978;5228.215767634847;5228.816991405282;5271.920921186185;5417.945820541783;5465.808696695636;5471.616986810925;5561.694106824123;5668.036433806263;4977.825776097856;6109.266887973427;6124.448799608057;6153.708437353894;6158.938410615881;6287.308396076473;6296.968515157395;6299.307076221612;6301.369863013708;6302.6218426894075;5542.445755424425;5576.386597474275;5706.087026075891;5741.282339707578;5764.5676574257095;6305.684715764193;6372.681365931685;6373.776917807947;6381.1066450696935;6395.072714456378;5063.468335824759;6015.4586087252355;6035.245594300713;6035.78874739382;6070.150178974947;6089.269287685506;6114.969425152845;6116.785912493041;6231.4391704746795;6256.906146407808;6074.149619053336;6129.417705317987;6129.785457509055;6171.907421388682;6185.536084793658;6143;6181.721822517998;6193.1329613854095;6198.938010619926;6199.101130336076;4848.369711937426;4896.46138924718;4898.559129678275;4906.362172917569;4913.410390753133;4333.956660433419;4347.152305300436;4366.061296821199;4387.407699960465;4424.668149888771;5730.426957304237;5764.913526297109;5772.9711351442975;5781.508571771446;5800.9419905800405;6113.602615829956;6295.842603934938;6296.685165741695;6300.180976473029;6322.810315690536;5613.073842815889;5665.830025099253;5705.971470142706;5741.339745929188;5746.683932383759;5315.548178404879;5316.441773613715;5316.946830531722;5348.7593058311695;5413.702246376487;5267.868303292448;6356.7073930620345;6385.744570217139;6401.775937842153;6407.231132264158;5106.46382129871;6261.843453913689;6267.185265915402;6282.120503129474;6290.465310448663;4607.562281583244;6133.8855759873895;6187.81436556904;6250.4374606285655;6256.280527739186;5979.611325263844;6037.366076561898;6061;6068.817935461943;6075.969620152047;6133;6149.200603921461;6160.969195153995;6191.442770150708;6192.969035154795;5916.674582897982;5918.260217472816;6055.878882422415;6067;6079.148919151234;6105.481034111973;6108.75564977396;6117.877642447215;6131.96934015327;6135.263768347827;5839.445252700986;5887.116932460165;5922;5945.940540594712;5988.910166347596;5895.616784908967;5906.527477801679;6034;6043.184170136975;6047.425494578008;5919.585629005928;5924.170616113834;5925.348211696796;5955.225820643289;5970.343262241063 20:29:16 INFO - PROCESS | 1907 | |1;ext.html;459.67363172147793;460.22222444069524;466.89728259782834;491.98770030749205;524.5541289903582;28933.421331573383;30717.846410767943;30721.539176912338;38006.859794206146;39119.022024449405;39721.40417893734;40133.799331003305;40156.59843401565;40265.59734402655;40281.99999999993;9226.354155209156;9356.859647105299;9367.531623418836;9370;9387.342885997949;8290.958545207266;8501.787455313604;9218.216451601622;9224.492652904088;9374;10988.615398461056;11172.608958686447;11180.776384472305;11187.44062796861;11205.21563490556;11108.333499989985;11128.38793866337;11186.216964812385;11207.831882521768;11227.775444491146;11053.557857685682;11080.889191108066;11101.72245693856;11103.611373601885;11137.220394572383;37584.624153758385;37824.43263351052;37842;37853.05367365838;37912.43131353032;3101.798383105091;3105.3634005028916;3186.362727454495;3228.2252259457796;3236.110069730843;27258.45483090347;27725.30686732848;28998.565021524693;29204.415911681648;29299.707002930125;3052.3895220955674;3188.601424821897;3280.212748940261;3323.5180898769786;3337.2824842658947;416669;416823.9158804217;417057;417551;417567.9121604403;56382;56723;56935.43064569384;56955;56995.71502142546;58333.41666583365;58503;58578.535536611926;58585;58601;15877.047377157407;16079.276432560562;16099.436519721754;16120.032798032156;16146.919265403598;12434.440450179764;12506.81239781404;12509.249445033327;12532.937335313265;12544.937275313565;13571.1857288563;14096.154230746188;14100.576982690536;15450.99568528042;15846.366145354057;6438.774642887476;6608.86782264352;6687.297833727486;6690.966545167243;6693.364130407604;808.3613944983515;959.5394210778866;1020.8621836052038;1024.4109636958779;1110.4614262082882;27563.586546201823;28175.43649127047;28326;28845;28926;1007.1388962437157;1029.4235228272191;1048.8741351037925;1087.3367245979946;1143.2911594811103;14913.105213687213;14990.700185996218;15287.312070956834;15446.922765386102;15601.453949111727;670400;677540;678527;679178;681729;50130;50962.49037509652;51071;53392;56054.43945560574;1318.8483324417623;1331.8601546837635;1352.0265408905573;1353.810466534686;1358.008653682817;28599.28501787472;29006.419871602873;29364.853175733984;29838.55242171326;30064;47535.52464475311;48042;48112.75943620259;48114;48284.51715482941;555881;560105;563554;570916;707620;24370.512589747752;24522.87738561296;24530.50938981175;24543.877280613484;24586.016559337426;43142;43434.56565434305;43477;43671.78164109159;44456;34619.30761384808;35318;36235.45646815247;37464.625353746116;38467.80766096152;37051.629483705896;37061;37382.439263411616;37889;38139.809300954425;35857.46213806847;36095.81952090223;36121.63878361183;36352.45471317879;36483.27033459369;31747.36505269928;33858;34043.14892127716;34131.48802767911;34614.82692586521;24355.878220608785;24657.260282191564;24843.50312993766;24978.875105624356;25399.238022859343 20:29:16 INFO - PROCESS | 1907 | |2;jquery.html;356.43327109895296;372.13293027246516;376.3094721186621;385.4007019085323;407.1531215072649;14470.710585788278;15051.698966020687;16167.999999999985;19644;20293.28973485928;19680.40958771239;19765;20231.898840505775;20350.694739578918;20553.69169462459;5294.311739473863;5773.884522309561;5991.5206783457215;5992.310884248302;5995.190649262359;5297.576193904479;5918.1418694289305;5966.79116230932;5970.164177015221;5972.163897054415;5639.8590035249035;5850.385709500505;5910.438508341701;5913.586048976574;5920.407959204067;5884.4703976642095;5932.080527518263;5939.643621382687;5950.315713692938;5957.672328022;5053.216751403502;5213.947860521384;5218.66078704883;5281.947180528223;5329.0674132027125;12954.676133096653;12981.286029268387;13000.544980925717;13244.933775331157;13245.337733113305;2019.3437132931708;2126.989365053165;2138.4760733620365;2161.6973623692697;2192.550527141932;9442.244620430349;9856.655017074369;10146.949265253626;10265.897341026568;10407.583696652126;1772.9645407091914;1858.386732378312;1923.1057558235489;2000.4398768344886;2012.3560460652598;104812;109364.4531777344;111583.44208278987;113299.43350283196;113988.43005785001;9529.140207839997;11311.773764524663;11342.659720208407;11521.942390288163;11639.06887449002;11996.340201288838;14574.562763117123;14587.343569539396;14591.92704036473;14592;8242.835143297101;8317.750467485985;8417.537035462985;8493.490390576586;8510.48937063778;8033;8149.144339844231;8182.631781569844;8353.37349698775;8365.163483651586;4382.145481631115;4450.910981780346;4458.286674132188;4529;4549.135664223787;1840.438666206793;1898.1932678611536;1907.1417861962173;1908.7136929460692;1917.3193516301792;666.5101150654265;697.8764189654652;755.9168491465934;801.703369753195;961.2409290997598;16993.575160620836;19729.506762331057;19808.22684824126;20101.281262966397;20374.391874096033;927.6660402255183;930.1210356213313;935.8409070458039;943.3255222515934;957.9952100239454;8715.956420217984;11177.83233251502;11275.54897804095;11276.436178191057;11344.773104537862;574992;580613;588015;590491;593755.0312248411;92295;117099;122575;126343.368283158;126695;861.2335021830576;882.9823403531893;894.9015608283083;902.7607683963755;933.290699068699;16221.270042848337;18564.628707425505;19031.809681903556;19235.807641923402;19568.706469402685;43870.34194487149;50564.49435505745;53664;54119.36069597161;55998.72000639971;75271.24728752642;77064.84402733852;77702;78506.6074669623;79931;6998.685059172248;7155.212926578176;7290.635468226674;7307.086614173229;7449.031625888817;9427.387219830689;12089.032877369966;12246.203996740183;12255.264684118982;12600.747985040065;10532.315399499008;10744.946275268887;11027;11164.330140191614;11231.101511878867;10381.325213861075;10450.895491044992;10598.89401105979;10744.247902647052;10871.67384978452;10827.404492752974;10873.891261087289;10883.891161088288;11311.434428278388;11317.943410282896;7050.294970502909;7283.963580182065;7314.890276645961;7389.556626602421;7459.850802984019;8427.536485493356;9700.660476883557;9747.853782193415;9833.459159746282;9868.703938881845 20:29:16 INFO - PROCESS | 1907 | |3;mootools.html;1432.6991331820318;1479.3786609623958;1619.5303362025013;1767.8143794901528;1822.9088545572727;7484.438667099976;8107.108218096012;8515.020772611151;8533.061363250044;8544.974603047644;7673.117591476982;7747.3027427531515;8008.439409241356;8248.958755206215;8254.752357429272;2275.2719129878446;2305.5965206088867;2309.503456756797;2313.618252988255;2313.826463015272;2307.261676263596;2331.1258278145697;2351.27110595715;2351.600227961242;2354.9646755298686;2027.9087441065187;2087.5720477302193;2234.2626933112033;2281.2243837095384;2282.0985710644286;2286.4512516995883;2286.965695514569;2310.5032418030037;2310.976890231093;2311.110222564308;2282.2468585366787;2292.6790249365104;2299.85050971688;2303.8156947444163;2304.665823455606;8312.625931833021;8336.749897503085;8428.367872409593;8435.536045517494;8449.281811046007;5020.3724534433195;5175.120229560946;5181.844544663666;5183.429822719498;5189.091908915962;3188.6970737779875;3308.139883630249;3513.1744040150543;3524.6299138590334;3527.2592755521373;1037.6783197208813;1046.8220402531588;1058.042471563229;1074.6346242277664;1091.4051841746268;10903.509342079626;11023.118150547936;11026.889731102747;11037.392943388111;11050.668479945614;3771.076086358805;4542.909141817145;4605.171069207575;4625.820415794018;4646.4888862225125;9246.121618446237;9459.290553208537;9461.763455913657;9483.241340692728;9487.051294870596;4545.318202269686;5175.896482070338;5182.274481572583;5184.11869982104;5187.948120518747;4689.132510485571;4699.459562150328;4723.645726570521;4740.525947405301;4747.192977193886;3838.213166300902;3844.826982785781;3864.961350386516;3947.3684210526176;3948.447217389568;1141.6860363399965;1246.840438392304;1248.4257241668934;1250.881166289201;1266.8986481081492;786.8662327404355;873.3362644390309;878.4948654523674;901.833160865242;958.2669258017622;5515.61390702655;5563.942850858405;5673.978683836949;5731.197632331477;5820.592558520862;855.2601999270711;864.3776480933718;897.8608315711075;941.538646063424;946.8106378724145;3392.6090302975663;3753.9624603753614;3803.7907915064384;3826.521684789401;3934.6458818705883;9749.902500975042;10220.897791022144;10229.846552301722;10232.53953572091;10239.84640230397;10131.797364052825;10146.985301469942;10148.492575371201;10169.288149829588;10170.745731356626;6979.89530157048;7699.384049275933;7786.688532458751;7891.684332626745;7898.01274840645;5702.201691763239;5743.3395159557185;5857.238558987305;5899.734511946886;5978.461938425563;9188.95405522968;9334.626614935598;9433.29250306216;9577.329586928849;9617.278704097082;9540.427574345562;9541.236701064037;9655.131038206595;9709.514524273616;9793.363431376938;7192.244814294424;7235.782926512213;7260.600666963368;7334.193238743627;7341.155767086853;7713.537187768752;7898.526088434713;7900.170482099296;7902.644381002984;7927.84144317122;7028.8594228114125;7160.785176444715;7188.676509556978;7205.171405288459;7287.599182045038;7073.469489788389;7095.254998225112;7118.822029449305;7125.821854453473;7195.172555156224;6621.5364924454825;6768.221654509597;6774.966125169342;6801.625910574966;6801.727930882709;4832.806687732451;4869.147899117711;4873.342098816614;4890.997345544226;4932.087563800708;4790.784414701417;4821.662483626113;4926.753662316942;4968.130577148912;4994.475580064186 20:29:16 INFO - PROCESS | 1907 | |4;prototype.html;238.8375904385018;303.3523427482326;309.5186984239508;312.31446973892304;315.36296680704993;4315.74105553667;4932.500262207635;5103.438621751614;5125.538701516867;5167.132241605907;4108.870060733299;4276.893077673052;4614.261718125101;4956.529129732669;4977.925331120027;3221.4362486564874;3880.6313400227004;3904;3915.099527108767;3922.1564365834683;3250.658680838513;3904.0239940014994;3905.1018265798884;3917.314469967744;3941.2314598653275;3671.247394284179;3817.5609804872515;3867.400552914303;3883.961160388388;3910.8240129194114;3525.6121826599056;3850.3646898261754;3850.4801851750076;3859.286032084073;3862;3018.6717844148725;3128.201284261536;3337.4826901830256;3343.966560334414;3345.946027001511;4924.138275801721;4964.925526117111;4966.975165124188;4972.563443912457;4984.551390374847;3565.23347480293;3749.212665340282;4099.631033206996;4275.87172384829;4431;5374.570034397278;5379.838604841861;5384.246205531229;5388.838334849961;5413.431589683066;3692.2615476904725;3700.9259814803822;3708.1471261609713;3712.201876596545;3715.312667156558;4629.606483448911;5281.788728450857;5529.447055294479;5604.523615492688;5607.8598035048635;3133.4046531159006;3133.6709645487354;3731.4962480065387;3736.954382178516;3759.436084587334;3773.4528493368307;3793.241351729665;3794.772313661189;3801.4297855321925;3823.5220597425323;3860.942085868714;4007.6393124618926;4249.745015299092;4379.255526560492;4389.122175564899;4422.3808666786035;4768.427788665313;5351.6918880300955;5399.300349825087;5404.351477822686;1044.7179261599326;1101.3061771084165;1122.2985633978763;1231.599730087725;1233.9568115116108;784.4587234807964;789.8538770327508;801.266840840636;830.2983978538126;853.6713365354383;4226.640735537452;4794.496577859344;4796.880077998008;4804.35141255926;4853.4418541867435;4946.629002824802;5034.867154890194;5239.1662917979165;5254.290670759475;5276.92084618731;4084.571120032413;4393.934850620203;4461.079606773565;4705.470722015359;4797.95202047982;2385.606374948114;2419.334682962164;2437.6465412515086;2523.9747602524108;2746.3134216445887;4692.193505500339;5081.4084816743725;5155.432902380735;5396.460353964572;5411.648242864201;4481.5070342262325;4946.802127914915;5086.821961231339;5375.623706340597;5384.9461505385225;4936.3829521309835;5154.948450515447;5377.848860455849;5399.487048730286;5411.5129638331955;4390.309730753619;4730.219513780187;4951.72765497901;5247.5539579134675;5277.8416647500635;4362.520122786555;4931.827386041471;5118.616103792156;5144.922826157535;5171.896562068813;4661.533846615312;4848.260732686021;5105.055564720538;5387.434319396563;5416.566674666096;3009.593704849817;3393.3043726035608;3535.717142628532;3548.717436371398;3571.4642803579673;4367.497737760198;4435.043533867823;4514.706544074625;5125.79496820123;5223.347081614798;4476.529964353696;5065.29085927963;5072.391313042459;5074.543291103819;5094.885622466635;4543.38664280318;4751.6436267279405;4873.1471992400475;5024.799008039784;5030.8239211627415;3992.1456158846563;4477.619402350837;4500.88747781295;4874.4150701916;5144.536991670768;5099.949000509948;5320.8935821282585;5359.785608575769;5388.245645609696;5423.078076727044;4727.38543989293;5275.417374787625;5291.629585928948;5296.629235953446;5322.361316642027 20:29:16 INFO - PROCESS | 1907 | |5;yui.html;688.232620628;690.6098054599152;706.6290197646232;760.3764912771526;792.932600728938;14178.149311041345;14545.418183272657;14547.981641285101;14559.854401455983;14581.97926145169;12906.096573239853;12912.483500659962;12913.483460661586;13007.414666340013;13039.543615973444;657.8750037492873;686.8488932434856;689.48633268215;822.3503432288514;846.5344060766585;831.2394159344193;849.8342823149488;850.1540966738073;850.8851305073829;857.5669287010052;909.440693973205;911.2983003087619;911.8267529169469;912.8448163812135;914.4193437167413;699.2657709405104;724.9166345870187;726.7855982485127;731.6890321613346;903.3631289940577;539.5602583894114;640.8013515810114;664.7540410048265;666.8066260784367;668.9799306020778;13948.58154255374;13992.580222593338;14035.78946315806;14048.297585120703;14068;12727.87272127276;12770.425330860133;12933.224006559636;13012.479500819956;13046.086773925828;11308.886911130949;11683.76632467346;11695.497931832797;12082.395880205955;12117.697057573543;4183.572624019793;4260.424842646234;4363.759993200372;4364.759938203398;4374.49693285273;77219;80139.19860801376;80210.59894700548;81210;82926.17073829245;9625.422474651543;9816.282150623612;9859.753506162404;10265.589376425009;10415.68752937413;18975.43073707791;19220.213069104902;19247.32634357819;19254.133563989653;19289.51776205578;6659.4006539411685;7201.819954501075;7261.092363454568;7445.7766267012075;7505.812354691177;3391.7117045051446;4121.29937910556;4166.0834616384345;4190.994161401241;4303.892402689895;3214.630317513469;3314.121757734202;3316.8839090631714;3326.8835590754206;3354.2285274387027;616.2820314333751;623.7785586116954;631.7599312261403;636.4749082007362;641.2433328672212;587.8177764892897;596.758312883283;614.3119705929388;623.488739233833;628.9088082228051;8405.201505856934;8425.873611895826;8527.317814574819;8656.220940115294;8690.695825646199;533.8958903013953;580.9564282678816;598.4524160393279;611.788932818177;621.552482212806;3064.969350306513;4077.3883917412627;4188.560201178893;4205.579442055831;4205.873823785291;69636.65181674059;85825.14174858296;117925;118713;119905.20142197874;56803;63531.68234158892;77619.61190194012;86477;94670.0532994675;91109.54445227864;91657.62513562436;92419;92636;94398;8032.518048917083;8263.628136733741;8338.62476188585;8473.70042433089;8498.065212826461;47055.76472117617;47725.04549909052;51534.22698659598;57678.7116064417;60279.09581356195;50908.49091509038;52289.738551307;68341;69394.30605693879;69560;12116.27302361861;12427.813582796447;12556.937215313865;12599.18105323151;12644.620661380173;13468.730625387634;13519.431308051438;13699.315034248448;14110.50613228532;14671.853281467049;13001.609951701465;13046.804297935349;13519.256440895846;13536.187828730308;13601.727965440834;12966.935165324114;13105.672358191121;13150.736985260433;13681.315934203449;13736.038477306492;12484.375781211085;12621.747565048465;12934.353282336035;12944;13178.472861085449;9564.808703825745;10640.521176547229;11030.393328367018;11402.087832973508;11510.53957841677;9409.388389754644;10552.736181595521;10932.424860034575;11006;11090.833637495283 20:29:16 INFO - PROCESS | 1907 | __end_tp_report 20:29:16 INFO - PROCESS | 1907 | __start_cc_report 20:29:16 INFO - PROCESS | 1907 | _x_x_mozilla_cycle_collect,1760 20:29:16 INFO - PROCESS | 1907 | __end_cc_report 20:29:16 INFO - PROCESS | 1907 | __startTimestamp1484713756545__endTimestamp 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | ------- Summary: start ------- 20:29:16 INFO - PROCESS | 1907 | Number of tests: 6 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | [#0] dojo.html Cycles:165 Average:5677.61 Median:5895.62 stddev:644.09 (10.9%) stddev-sans-first:618.80 20:29:16 INFO - PROCESS | 1907 | Values: 3313.9 3323.0 3342.4 3360.9 3388.3 5616.6 5656.1 5676.6 5686.1 5735.1 5604.6 5662.9 5665.5 5672.0 5676.2 5174.8 5181.1 5188.1 5207.9 5213.7 5109.4 5118.9 5136.7 5200.9 5272.5 5130.3 5214.8 5219.4 5224.2 5244.4 5193.4 5203.8 5222.8 5236.2 5256.4 5107.7 5205.3 5228.2 5228.8 5271.9 5417.9 5465.8 5471.6 5561.7 5668.0 4977.8 6109.3 6124.4 6153.7 6158.9 6287.3 6297.0 6299.3 6301.4 6302.6 5542.4 5576.4 5706.1 5741.3 5764.6 6305.7 6372.7 6373.8 6381.1 6395.1 5063.5 6015.5 6035.2 6035.8 6070.2 6089.3 6115.0 6116.8 6231.4 6256.9 6074.1 6129.4 6129.8 6171.9 6185.5 6143.0 6181.7 6193.1 6198.9 6199.1 4848.4 4896.5 4898.6 4906.4 4913.4 4334.0 4347.2 4366.1 4387.4 4424.7 5730.4 5764.9 5773.0 5781.5 5800.9 6113.6 6295.8 6296.7 6300.2 6322.8 5613.1 5665.8 5706.0 5741.3 5746.7 5315.5 5316.4 5316.9 5348.8 5413.7 5267.9 6356.7 6385.7 6401.8 6407.2 5106.5 6261.8 6267.2 6282.1 6290.5 4607.6 6133.9 6187.8 6250.4 6256.3 5979.6 6037.4 6061.0 6068.8 6076.0 6133.0 6149.2 6161.0 6191.4 6193.0 5916.7 5918.3 6055.9 6067.0 6079.1 6105.5 6108.8 6117.9 6132.0 6135.3 5839.4 5887.1 5922.0 5945.9 5988.9 5895.6 5906.5 6034.0 6043.2 6047.4 5919.6 5924.2 5925.3 5955.2 5970.3 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | [#1] ext.html Cycles:180 Average:68425.80 Median:34617.07 stddev:153720.91 (444.1%) stddev-sans-first:154067.43 20:29:16 INFO - PROCESS | 1907 | Values: 459.7 460.2 466.9 492.0 524.6 28933.4 30717.8 30721.5 38006.9 39119.0 39721.4 40133.8 40156.6 40265.6 40282.0 9226.4 9356.9 9367.5 9370.0 9387.3 8291.0 8501.8 9218.2 9224.5 9374.0 10988.6 11172.6 11180.8 11187.4 11205.2 11108.3 11128.4 11186.2 11207.8 11227.8 11053.6 11080.9 11101.7 11103.6 11137.2 37584.6 37824.4 37842.0 37853.1 37912.4 3101.8 3105.4 3186.4 3228.2 3236.1 27258.5 27725.3 28998.6 29204.4 29299.7 3052.4 3188.6 3280.2 3323.5 3337.3 416669.0 416823.9 417057.0 417551.0 417567.9 56382.0 56723.0 56935.4 56955.0 56995.7 58333.4 58503.0 58578.5 58585.0 58601.0 15877.0 16079.3 16099.4 16120.0 16146.9 12434.4 12506.8 12509.2 12532.9 12544.9 13571.2 14096.2 14100.6 15451.0 15846.4 6438.8 6608.9 6687.3 6691.0 6693.4 808.4 959.5 1020.9 1024.4 1110.5 27563.6 28175.4 28326.0 28845.0 28926.0 1007.1 1029.4 1048.9 1087.3 1143.3 14913.1 14990.7 15287.3 15446.9 15601.5 670400.0 677540.0 678527.0 679178.0 681729.0 50130.0 50962.5 51071.0 53392.0 56054.4 1318.8 1331.9 1352.0 1353.8 1358.0 28599.3 29006.4 29364.9 29838.6 30064.0 47535.5 48042.0 48112.8 48114.0 48284.5 555881.0 560105.0 563554.0 570916.0 707620.0 24370.5 24522.9 24530.5 24543.9 24586.0 43142.0 43434.6 43477.0 43671.8 44456.0 34619.3 35318.0 36235.5 37464.6 38467.8 37051.6 37061.0 37382.4 37889.0 38139.8 35857.5 36095.8 36121.6 36352.5 36483.3 31747.4 33858.0 34043.1 34131.5 34614.8 24355.9 24657.3 24843.5 24978.9 25399.2 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | [#2] jquery.html Cycles:180 Average:33436.47 Median:24160.63 stddev:97551.27 (403.8%) stddev-sans-first:97793.30 20:29:16 INFO - PROCESS | 1907 | Values: 356.4 372.1 376.3 385.4 407.2 14470.7 15051.7 16168.0 19644.0 20293.3 19680.4 19765.0 20231.9 20350.7 20553.7 5294.3 5773.9 5991.5 5992.3 5995.2 5297.6 5918.1 5966.8 5970.2 5972.2 5639.9 5850.4 5910.4 5913.6 5920.4 5884.5 5932.1 5939.6 5950.3 5957.7 5053.2 5213.9 5218.7 5281.9 5329.1 12954.7 12981.3 13000.5 13244.9 13245.3 2019.3 2127.0 2138.5 2161.7 2192.6 9442.2 9856.7 10146.9 10265.9 10407.6 1773.0 1858.4 1923.1 2000.4 2012.4 104812.0 109364.5 111583.4 113299.4 113988.4 9529.1 11311.8 11342.7 11521.9 11639.1 11996.3 14574.6 14587.3 14591.9 14592.0 8242.8 8317.8 8417.5 8493.5 8510.5 8033.0 8149.1 8182.6 8353.4 8365.2 4382.1 4450.9 4458.3 4529.0 4549.1 1840.4 1898.2 1907.1 1908.7 1917.3 666.5 697.9 755.9 801.7 961.2 16993.6 19729.5 19808.2 20101.3 20374.4 927.7 930.1 935.8 943.3 958.0 8716.0 11177.8 11275.5 11276.4 11344.8 574992.0 580613.0 588015.0 590491.0 593755.0 92295.0 117099.0 122575.0 126343.4 126695.0 861.2 883.0 894.9 902.8 933.3 16221.3 18564.6 19031.8 19235.8 19568.7 43870.3 50564.5 53664.0 54119.4 55998.7 75271.2 77064.8 77702.0 78506.6 79931.0 6998.7 7155.2 7290.6 7307.1 7449.0 9427.4 12089.0 12246.2 12255.3 12600.7 10532.3 10744.9 11027.0 11164.3 11231.1 10381.3 10450.9 10598.9 10744.2 10871.7 10827.4 10873.9 10883.9 11311.4 11317.9 7050.3 7284.0 7314.9 7389.6 7459.9 8427.5 9700.7 9747.9 9833.5 9868.7 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | [#3] mootools.html Cycles:180 Average:5474.04 Median:5097.75 stddev:3012.30 (59.1%) stddev-sans-first:3005.44 20:29:16 INFO - PROCESS | 1907 | Values: 1432.7 1479.4 1619.5 1767.8 1822.9 7484.4 8107.1 8515.0 8533.1 8545.0 7673.1 7747.3 8008.4 8249.0 8254.8 2275.3 2305.6 2309.5 2313.6 2313.8 2307.3 2331.1 2351.3 2351.6 2355.0 2027.9 2087.6 2234.3 2281.2 2282.1 2286.5 2287.0 2310.5 2311.0 2311.1 2282.2 2292.7 2299.9 2303.8 2304.7 8312.6 8336.7 8428.4 8435.5 8449.3 5020.4 5175.1 5181.8 5183.4 5189.1 3188.7 3308.1 3513.2 3524.6 3527.3 1037.7 1046.8 1058.0 1074.6 1091.4 10903.5 11023.1 11026.9 11037.4 11050.7 3771.1 4542.9 4605.2 4625.8 4646.5 9246.1 9459.3 9461.8 9483.2 9487.1 4545.3 5175.9 5182.3 5184.1 5187.9 4689.1 4699.5 4723.6 4740.5 4747.2 3838.2 3844.8 3865.0 3947.4 3948.4 1141.7 1246.8 1248.4 1250.9 1266.9 786.9 873.3 878.5 901.8 958.3 5515.6 5563.9 5674.0 5731.2 5820.6 855.3 864.4 897.9 941.5 946.8 3392.6 3754.0 3803.8 3826.5 3934.6 9749.9 10220.9 10229.8 10232.5 10239.8 10131.8 10147.0 10148.5 10169.3 10170.7 6979.9 7699.4 7786.7 7891.7 7898.0 5702.2 5743.3 5857.2 5899.7 5978.5 9189.0 9334.6 9433.3 9577.3 9617.3 9540.4 9541.2 9655.1 9709.5 9793.4 7192.2 7235.8 7260.6 7334.2 7341.2 7713.5 7898.5 7900.2 7902.6 7927.8 7028.9 7160.8 7188.7 7205.2 7287.6 7073.5 7095.3 7118.8 7125.8 7195.2 6621.5 6768.2 6775.0 6801.6 6801.7 4832.8 4869.1 4873.3 4891.0 4932.1 4790.8 4821.7 4926.8 4968.1 4994.5 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | [#4] prototype.html Cycles:180 Average:4178.38 Median:4645.57 stddev:1276.59 (27.5%) stddev-sans-first:1245.46 20:29:16 INFO - PROCESS | 1907 | Values: 238.8 303.4 309.5 312.3 315.4 4315.7 4932.5 5103.4 5125.5 5167.1 4108.9 4276.9 4614.3 4956.5 4977.9 3221.4 3880.6 3904.0 3915.1 3922.2 3250.7 3904.0 3905.1 3917.3 3941.2 3671.2 3817.6 3867.4 3884.0 3910.8 3525.6 3850.4 3850.5 3859.3 3862.0 3018.7 3128.2 3337.5 3344.0 3345.9 4924.1 4964.9 4967.0 4972.6 4984.6 3565.2 3749.2 4099.6 4275.9 4431.0 5374.6 5379.8 5384.2 5388.8 5413.4 3692.3 3700.9 3708.1 3712.2 3715.3 4629.6 5281.8 5529.4 5604.5 5607.9 3133.4 3133.7 3731.5 3737.0 3759.4 3773.5 3793.2 3794.8 3801.4 3823.5 3860.9 4007.6 4249.7 4379.3 4389.1 4422.4 4768.4 5351.7 5399.3 5404.4 1044.7 1101.3 1122.3 1231.6 1234.0 784.5 789.9 801.3 830.3 853.7 4226.6 4794.5 4796.9 4804.4 4853.4 4946.6 5034.9 5239.2 5254.3 5276.9 4084.6 4393.9 4461.1 4705.5 4798.0 2385.6 2419.3 2437.6 2524.0 2746.3 4692.2 5081.4 5155.4 5396.5 5411.6 4481.5 4946.8 5086.8 5375.6 5384.9 4936.4 5154.9 5377.8 5399.5 5411.5 4390.3 4730.2 4951.7 5247.6 5277.8 4362.5 4931.8 5118.6 5144.9 5171.9 4661.5 4848.3 5105.1 5387.4 5416.6 3009.6 3393.3 3535.7 3548.7 3571.5 4367.5 4435.0 4514.7 5125.8 5223.3 4476.5 5065.3 5072.4 5074.5 5094.9 4543.4 4751.6 4873.1 5024.8 5030.8 3992.1 4477.6 4500.9 4874.4 5144.5 5099.9 5320.9 5359.8 5388.2 5423.1 4727.4 5275.4 5291.6 5296.6 5322.4 20:29:16 INFO - PROCESS | 1907 | 20:29:16 INFO - PROCESS | 1907 | [#5] yui.html Cycles:180 Average:19297.37 Median:51221.36 stddev:27928.21 (54.5%) stddev-sans-first:27971.61 20:29:16 INFO - PROCESS | 1907 | Values: 688.2 690.6 706.6 760.4 792.9 14178.1 14545.4 14548.0 14559.9 14582.0 12906.1 12912.5 12913.5 13007.4 13039.5 657.9 686.8 689.5 822.4 846.5 831.2 849.8 850.2 850.9 857.6 909.4 911.3 911.8 912.8 914.4 699.3 724.9 726.8 731.7 903.4 539.6 640.8 664.8 666.8 669.0 13948.6 13992.6 14035.8 14048.3 14068.0 12727.9 12770.4 12933.2 13012.5 13046.1 11308.9 11683.8 11695.5 12082.4 12117.7 4183.6 4260.4 4363.8 4364.8 4374.5 77219.0 80139.2 80210.6 81210.0 82926.2 9625.4 9816.3 9859.8 10265.6 10415.7 18975.4 19220.2 19247.3 19254.1 19289.5 6659.4 7201.8 7261.1 7445.8 7505.8 3391.7 4121.3 4166.1 4191.0 4303.9 3214.6 3314.1 3316.9 3326.9 3354.2 616.3 623.8 631.8 636.5 641.2 587.8 596.8 614.3 623.5 628.9 8405.2 8425.9 8527.3 8656.2 8690.7 533.9 581.0 598.5 611.8 621.6 3065.0 4077.4 4188.6 4205.6 4205.9 69636.7 85825.1 117925.0 118713.0 119905.2 56803.0 63531.7 77619.6 86477.0 94670.1 91109.5 91657.6 92419.0 92636.0 94398.0 8032.5 8263.6 8338.6 8473.7 8498.1 47055.8 47725.0 51534.2 57678.7 60279.1 50908.5 52289.7 68341.0 69394.3 69560.0 12116.3 12427.8 12556.9 12599.2 12644.6 13468.7 13519.4 13699.3 14110.5 14671.9 13001.6 13046.8 13519.3 13536.2 13601.7 12966.9 13105.7 13150.7 13681.3 13736.0 12484.4 12621.7 12934.4 12944.0 13178.5 9564.8 10640.5 11030.4 11402.1 11510.5 9409.4 10552.7 10932.4 11006.0 11090.8 20:29:16 INFO - PROCESS | 1907 | -------- Summary: end -------- 20:29:16 INFO - PROCESS | 1907 | 20:29:17 INFO - TEST-INFO | 1907: exit 0 20:29:17 INFO - TEST-OK | dromaeo_css | took 1118595ms 20:29:17 INFO - TEST-START | kraken 20:29:17 INFO - operating with platform_type : mac_ 20:29:17 INFO - Initialising browser for kraken test... 20:29:17 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpR_LiSI/profile http://localhost:53701/getInfo.html) 20:29:19 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 20:29:19 INFO - PROCESS | 1910 | colorDepth:24 20:29:19 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 20:29:19 INFO - PROCESS | 1910 | __metrics 20:29:19 INFO - PROCESS | 1910 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:29:19 INFO - PROCESS | 1910 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:29:20 INFO - TEST-INFO | 1910: exit 0 20:29:20 INFO - Browser initialized. 20:29:20 INFO - Running cycle 1/1 for kraken test... 20:29:20 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/T/tmpR_LiSI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:29:33 INFO - PROCESS | 1911 | RSS: Main: 239214592 20:29:33 INFO - PROCESS | 1911 | 20:29:35 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53701/tests/kraken/driver.html?testName=audio-beat-detection) 20:29:36 INFO - PROCESS | 1911 | RSS: Main: 285827072 20:29:36 INFO - PROCESS | 1911 | 20:29:39 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53701/tests/kraken/driver.html?testName=audio-dft) 20:29:40 INFO - PROCESS | 1911 | RSS: Main: 297971712 20:29:40 INFO - PROCESS | 1911 | 20:29:43 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53701/tests/kraken/driver.html?testName=audio-fft) 20:29:43 INFO - PROCESS | 1911 | RSS: Main: 390787072 20:29:43 INFO - PROCESS | 1911 | 20:29:46 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53701/tests/kraken/driver.html?testName=audio-oscillator) 20:29:46 INFO - PROCESS | 1911 | RSS: Main: 293933056 20:29:46 INFO - PROCESS | 1911 | 20:29:48 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53701/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:29:49 INFO - PROCESS | 1911 | RSS: Main: 294813696 20:29:49 INFO - PROCESS | 1911 | 20:29:52 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53701/tests/kraken/driver.html?testName=imaging-darkroom) 20:29:52 INFO - PROCESS | 1911 | RSS: Main: 335167488 20:29:52 INFO - PROCESS | 1911 | 20:29:55 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53701/tests/kraken/driver.html?testName=imaging-desaturate) 20:29:56 INFO - PROCESS | 1911 | RSS: Main: 337281024 20:29:56 INFO - PROCESS | 1911 | 20:29:58 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53701/tests/kraken/driver.html?testName=json-parse-financial) 20:29:59 INFO - PROCESS | 1911 | RSS: Main: 334479360 20:29:59 INFO - PROCESS | 1911 | 20:30:01 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53701/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:30:01 INFO - PROCESS | 1911 | RSS: Main: 307015680 20:30:01 INFO - PROCESS | 1911 | 20:30:03 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:30:04 INFO - PROCESS | 1911 | RSS: Main: 311844864 20:30:04 INFO - PROCESS | 1911 | 20:30:06 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:30:06 INFO - PROCESS | 1911 | RSS: Main: 313757696 20:30:06 INFO - PROCESS | 1911 | 20:30:09 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:30:10 INFO - PROCESS | 1911 | RSS: Main: 329871360 20:30:10 INFO - PROCESS | 1911 | 20:30:13 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:30:13 INFO - PROCESS | 1911 | RSS: Main: 323256320 20:30:13 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | Cycle 1(1): loaded http://localhost:53701/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53701/tests/kraken/driver.html?testName=ai-astar) 20:30:15 INFO - PROCESS | 1911 | RSS: Main: 367742976 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | __start_tp_report 20:30:15 INFO - PROCESS | 1911 | _x_x_mozilla_page_load 20:30:15 INFO - PROCESS | 1911 | _x_x_mozilla_page_load_details 20:30:15 INFO - PROCESS | 1911 | |i|pagename|runs| 20:30:15 INFO - PROCESS | 1911 | |0;ai-astar;114;112;113;111;114;114;115;113;114;114 20:30:15 INFO - PROCESS | 1911 | |1;audio-beat-detection;144;111;109;146;115;141;140;142;138;109 20:30:15 INFO - PROCESS | 1911 | |2;audio-dft;184;196;266;203;182;184;183;183;183;185 20:30:15 INFO - PROCESS | 1911 | |3;audio-fft;83;59;59;89;60;81;82;82;109;58 20:30:15 INFO - PROCESS | 1911 | |4;audio-oscillator;91;70;95;71;68;70;89;67;69;92 20:30:15 INFO - PROCESS | 1911 | |5;imaging-gaussian-blur;87;88;135;84;90;130;87;83;87;83 20:30:15 INFO - PROCESS | 1911 | |6;imaging-darkroom;92;92;92;92;91;92;93;93;93;92 20:30:15 INFO - PROCESS | 1911 | |7;imaging-desaturate;86;85;83;82;83;82;85;85;84;84 20:30:15 INFO - PROCESS | 1911 | |8;json-parse-financial;54;52;54;53;56;57;58;60;56;58 20:30:15 INFO - PROCESS | 1911 | |9;json-stringify-tinderbox;66;69;81;76;71;70;69;69;69;70 20:30:15 INFO - PROCESS | 1911 | |10;stanford-crypto-aes;84;84;83;84;84;83;82;84;84;83 20:30:15 INFO - PROCESS | 1911 | |11;stanford-crypto-ccm;139;140;141;134;134;140;142;142;179;142 20:30:15 INFO - PROCESS | 1911 | |12;stanford-crypto-pbkdf2;167;214;164;165;165;166;166;160;164;164 20:30:15 INFO - PROCESS | 1911 | |13;stanford-crypto-sha256-iterative;69;66;69;68;67;68;66;67;67;66 20:30:15 INFO - PROCESS | 1911 | __end_tp_report 20:30:15 INFO - PROCESS | 1911 | __start_cc_report 20:30:15 INFO - PROCESS | 1911 | _x_x_mozilla_cycle_collect,1917 20:30:15 INFO - PROCESS | 1911 | __end_cc_report 20:30:15 INFO - PROCESS | 1911 | __startTimestamp1484713815950__endTimestamp 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | ------- Summary: start ------- 20:30:15 INFO - PROCESS | 1911 | Number of tests: 14 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:1.17 (1.0%) stddev-sans-first:1.22 20:30:15 INFO - PROCESS | 1911 | Values: 114.0 112.0 113.0 111.0 114.0 114.0 115.0 113.0 114.0 114.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:140.50 stddev:16.15 (11.5%) stddev-sans-first:16.25 20:30:15 INFO - PROCESS | 1911 | Values: 144.0 111.0 109.0 146.0 115.0 141.0 140.0 142.0 138.0 109.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#2] audio-dft Cycles:10 Average:194.90 Median:184.50 stddev:25.92 (14.1%) stddev-sans-first:27.20 20:30:15 INFO - PROCESS | 1911 | Values: 184.0 196.0 266.0 203.0 182.0 184.0 183.0 183.0 183.0 185.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#3] audio-fft Cycles:10 Average:76.20 Median:81.50 stddev:16.87 (20.7%) stddev-sans-first:17.71 20:30:15 INFO - PROCESS | 1911 | Values: 83.0 59.0 59.0 89.0 60.0 81.0 82.0 82.0 109.0 58.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:80.00 stddev:11.80 (14.8%) stddev-sans-first:11.57 20:30:15 INFO - PROCESS | 1911 | Values: 91.0 70.0 95.0 71.0 68.0 70.0 89.0 67.0 69.0 92.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:87.00 stddev:19.72 (22.7%) stddev-sans-first:20.68 20:30:15 INFO - PROCESS | 1911 | Values: 87.0 88.0 135.0 84.0 90.0 130.0 87.0 83.0 87.0 83.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#6] imaging-darkroom Cycles:10 Average:92.20 Median:92.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 20:30:15 INFO - PROCESS | 1911 | Values: 92.0 92.0 92.0 92.0 91.0 92.0 93.0 93.0 93.0 92.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.50 stddev:1.37 (1.6%) stddev-sans-first:1.22 20:30:15 INFO - PROCESS | 1911 | Values: 86.0 85.0 83.0 82.0 83.0 82.0 85.0 85.0 84.0 84.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:56.50 stddev:2.53 (4.5%) stddev-sans-first:2.60 20:30:15 INFO - PROCESS | 1911 | Values: 54.0 52.0 54.0 53.0 56.0 57.0 58.0 60.0 56.0 58.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.00 stddev:4.32 (6.2%) stddev-sans-first:4.19 20:30:15 INFO - PROCESS | 1911 | Values: 66.0 69.0 81.0 76.0 71.0 70.0 69.0 69.0 69.0 70.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:0.71 (0.8%) stddev-sans-first:0.73 20:30:15 INFO - PROCESS | 1911 | Values: 84.0 84.0 83.0 84.0 84.0 83.0 82.0 84.0 84.0 83.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:141.50 stddev:12.90 (9.1%) stddev-sans-first:13.59 20:30:15 INFO - PROCESS | 1911 | Values: 139.0 140.0 141.0 134.0 134.0 140.0 142.0 142.0 179.0 142.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:165.50 stddev:15.75 (9.5%) stddev-sans-first:16.68 20:30:15 INFO - PROCESS | 1911 | Values: 167.0 214.0 164.0 165.0 165.0 166.0 166.0 160.0 164.0 164.0 20:30:15 INFO - PROCESS | 1911 | 20:30:15 INFO - PROCESS | 1911 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.16 (1.7%) stddev-sans-first:1.05 20:30:15 INFO - PROCESS | 1911 | Values: 69.0 66.0 69.0 68.0 67.0 68.0 66.0 67.0 67.0 66.0 20:30:15 INFO - PROCESS | 1911 | -------- Summary: end -------- 20:30:15 INFO - PROCESS | 1911 | 20:30:16 INFO - TEST-INFO | 1911: exit 0 20:30:17 INFO - TEST-OK | kraken | took 59805ms 20:30:17 INFO - SUITE-END | took 1178s 20:30:17 INFO - Completed test suite (00:19:38) 20:30:17 INFO - javascript benchmark 20:30:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.8840140595084, 3323.01970918579, 3342.4484959981605, 3360.8823691170815, 3388.322335532894, 5616.550675945925, 5656.066748986423, 5676.631018983763, 5686.061799803033, 5735.139729040641, 5604.579656525756, 5662.858428539278, 5665.5467562594995, 5672.035753921833, 5676.233708449358, 5174.793008279665, 5181.0674078665825, 5188.066148093341, 5207.869803254911, 5213.713245771481, 5109.386873575176, 5118.872028199287, 5136.691798492084, 5200.92198617021, 5272.499112584317, 5130.281760553526, 5214.817481388152, 5219.399769026553, 5224.216367544861, 5244.370675518943, 5193.350831146106, 5203.843884683465, 5222.817201397971, 5236.240745091978, 5256.448072952361, 5107.668001579886, 5205.323307969978, 5228.215767634847, 5228.816991405282, 5271.920921186185, 5417.945820541783, 5465.808696695636, 5471.616986810925, 5561.694106824123, 5668.036433806263, 4977.825776097856, 6109.266887973427, 6124.448799608057, 6153.708437353894, 6158.938410615881, 6287.308396076473, 6296.968515157395, 6299.307076221612, 6301.369863013708, 6302.6218426894075, 5542.445755424425, 5576.386597474275, 5706.087026075891, 5741.282339707578, 5764.5676574257095, 6305.684715764193, 6372.681365931685, 6373.776917807947, 6381.1066450696935, 6395.072714456378, 5063.468335824759, 6015.4586087252355, 6035.245594300713, 6035.78874739382, 6070.150178974947, 6089.269287685506, 6114.969425152845, 6116.785912493041, 6231.4391704746795, 6256.906146407808, 6074.149619053336, 6129.417705317987, 6129.785457509055, 6171.907421388682, 6185.536084793658, 6143.0, 6181.721822517998, 6193.1329613854095, 6198.938010619926, 6199.101130336076, 4848.369711937426, 4896.46138924718, 4898.559129678275, 4906.362172917569, 4913.410390753133, 4333.956660433419, 4347.152305300436, 4366.061296821199, 4387.407699960465, 4424.668149888771, 5730.426957304237, 5764.913526297109, 5772.9711351442975, 5781.508571771446, 5800.9419905800405, 6113.602615829956, 6295.842603934938, 6296.685165741695, 6300.180976473029, 6322.810315690536, 5613.073842815889, 5665.830025099253, 5705.971470142706, 5741.339745929188, 5746.683932383759, 5315.548178404879, 5316.441773613715, 5316.946830531722, 5348.7593058311695, 5413.702246376487, 5267.868303292448, 6356.7073930620345, 6385.744570217139, 6401.775937842153, 6407.231132264158, 5106.46382129871, 6261.843453913689, 6267.185265915402, 6282.120503129474, 6290.465310448663, 4607.562281583244, 6133.8855759873895, 6187.81436556904, 6250.4374606285655, 6256.280527739186, 5979.611325263844, 6037.366076561898, 6061.0, 6068.817935461943, 6075.969620152047, 6133.0, 6149.200603921461, 6160.969195153995, 6191.442770150708, 6192.969035154795, 5916.674582897982, 5918.260217472816, 6055.878882422415, 6067.0, 6079.148919151234, 6105.481034111973, 6108.75564977396, 6117.877642447215, 6131.96934015327, 6135.263768347827, 5839.445252700986, 5887.116932460165, 5922.0, 5945.940540594712, 5988.910166347596, 5895.616784908967, 5906.527477801679, 6034.0, 6043.184170136975, 6047.425494578008, 5919.585629005928, 5924.170616113834, 5925.348211696796, 5955.225820643289, 5970.343262241063], "value": 5638.265019388754, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.67363172147793, 460.22222444069524, 466.89728259782834, 491.98770030749205, 524.5541289903582, 28933.421331573383, 30717.846410767943, 30721.539176912338, 38006.859794206146, 39119.022024449405, 39721.40417893734, 40133.799331003305, 40156.59843401565, 40265.59734402655, 40281.99999999993, 9226.354155209156, 9356.859647105299, 9367.531623418836, 9370.0, 9387.342885997949, 8290.958545207266, 8501.787455313604, 9218.216451601622, 9224.492652904088, 9374.0, 10988.615398461056, 11172.608958686447, 11180.776384472305, 11187.44062796861, 11205.21563490556, 11108.333499989985, 11128.38793866337, 11186.216964812385, 11207.831882521768, 11227.775444491146, 11053.557857685682, 11080.889191108066, 11101.72245693856, 11103.611373601885, 11137.220394572383, 37584.624153758385, 37824.43263351052, 37842.0, 37853.05367365838, 37912.43131353032, 3101.798383105091, 3105.3634005028916, 3186.362727454495, 3228.2252259457796, 3236.110069730843, 27258.45483090347, 27725.30686732848, 28998.565021524693, 29204.415911681648, 29299.707002930125, 3052.3895220955674, 3188.601424821897, 3280.212748940261, 3323.5180898769786, 3337.2824842658947, 416669.0, 416823.9158804217, 417057.0, 417551.0, 417567.9121604403, 56382.0, 56723.0, 56935.43064569384, 56955.0, 56995.71502142546, 58333.41666583365, 58503.0, 58578.535536611926, 58585.0, 58601.0, 15877.047377157407, 16079.276432560562, 16099.436519721754, 16120.032798032156, 16146.919265403598, 12434.440450179764, 12506.81239781404, 12509.249445033327, 12532.937335313265, 12544.937275313565, 13571.1857288563, 14096.154230746188, 14100.576982690536, 15450.99568528042, 15846.366145354057, 6438.774642887476, 6608.86782264352, 6687.297833727486, 6690.966545167243, 6693.364130407604, 808.3613944983515, 959.5394210778866, 1020.8621836052038, 1024.4109636958779, 1110.4614262082882, 27563.586546201823, 28175.43649127047, 28326.0, 28845.0, 28926.0, 1007.1388962437157, 1029.4235228272191, 1048.8741351037925, 1087.3367245979946, 1143.2911594811103, 14913.105213687213, 14990.700185996218, 15287.312070956834, 15446.922765386102, 15601.453949111727, 670400.0, 677540.0, 678527.0, 679178.0, 681729.0, 50130.0, 50962.49037509652, 51071.0, 53392.0, 56054.43945560574, 1318.8483324417623, 1331.8601546837635, 1352.0265408905573, 1353.810466534686, 1358.008653682817, 28599.28501787472, 29006.419871602873, 29364.853175733984, 29838.55242171326, 30064.0, 47535.52464475311, 48042.0, 48112.75943620259, 48114.0, 48284.51715482941, 555881.0, 560105.0, 563554.0, 570916.0, 707620.0, 24370.512589747752, 24522.87738561296, 24530.50938981175, 24543.877280613484, 24586.016559337426, 43142.0, 43434.56565434305, 43477.0, 43671.78164109159, 44456.0, 34619.30761384808, 35318.0, 36235.45646815247, 37464.625353746116, 38467.80766096152, 37051.629483705896, 37061.0, 37382.439263411616, 37889.0, 38139.809300954425, 35857.46213806847, 36095.81952090223, 36121.63878361183, 36352.45471317879, 36483.27033459369, 31747.36505269928, 33858.0, 34043.14892127716, 34131.48802767911, 34614.82692586521, 24355.878220608785, 24657.260282191564, 24843.50312993766, 24978.875105624356, 25399.238022859343], "value": 19261.42146295634, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.43327109895296, 372.13293027246516, 376.3094721186621, 385.4007019085323, 407.1531215072649, 14470.710585788278, 15051.698966020687, 16167.999999999985, 19644.0, 20293.28973485928, 19680.40958771239, 19765.0, 20231.898840505775, 20350.694739578918, 20553.69169462459, 5294.311739473863, 5773.884522309561, 5991.5206783457215, 5992.310884248302, 5995.190649262359, 5297.576193904479, 5918.1418694289305, 5966.79116230932, 5970.164177015221, 5972.163897054415, 5639.8590035249035, 5850.385709500505, 5910.438508341701, 5913.586048976574, 5920.407959204067, 5884.4703976642095, 5932.080527518263, 5939.643621382687, 5950.315713692938, 5957.672328022, 5053.216751403502, 5213.947860521384, 5218.66078704883, 5281.947180528223, 5329.0674132027125, 12954.676133096653, 12981.286029268387, 13000.544980925717, 13244.933775331157, 13245.337733113305, 2019.3437132931708, 2126.989365053165, 2138.4760733620365, 2161.6973623692697, 2192.550527141932, 9442.244620430349, 9856.655017074369, 10146.949265253626, 10265.897341026568, 10407.583696652126, 1772.9645407091914, 1858.386732378312, 1923.1057558235489, 2000.4398768344886, 2012.3560460652598, 104812.0, 109364.4531777344, 111583.44208278987, 113299.43350283196, 113988.43005785001, 9529.140207839997, 11311.773764524663, 11342.659720208407, 11521.942390288163, 11639.06887449002, 11996.340201288838, 14574.562763117123, 14587.343569539396, 14591.92704036473, 14592.0, 8242.835143297101, 8317.750467485985, 8417.537035462985, 8493.490390576586, 8510.48937063778, 8033.0, 8149.144339844231, 8182.631781569844, 8353.37349698775, 8365.163483651586, 4382.145481631115, 4450.910981780346, 4458.286674132188, 4529.0, 4549.135664223787, 1840.438666206793, 1898.1932678611536, 1907.1417861962173, 1908.7136929460692, 1917.3193516301792, 666.5101150654265, 697.8764189654652, 755.9168491465934, 801.703369753195, 961.2409290997598, 16993.575160620836, 19729.506762331057, 19808.22684824126, 20101.281262966397, 20374.391874096033, 927.6660402255183, 930.1210356213313, 935.8409070458039, 943.3255222515934, 957.9952100239454, 8715.956420217984, 11177.83233251502, 11275.54897804095, 11276.436178191057, 11344.773104537862, 574992.0, 580613.0, 588015.0, 590491.0, 593755.0312248411, 92295.0, 117099.0, 122575.0, 126343.368283158, 126695.0, 861.2335021830576, 882.9823403531893, 894.9015608283083, 902.7607683963755, 933.290699068699, 16221.270042848337, 18564.628707425505, 19031.809681903556, 19235.807641923402, 19568.706469402685, 43870.34194487149, 50564.49435505745, 53664.0, 54119.36069597161, 55998.72000639971, 75271.24728752642, 77064.84402733852, 77702.0, 78506.6074669623, 79931.0, 6998.685059172248, 7155.212926578176, 7290.635468226674, 7307.086614173229, 7449.031625888817, 9427.387219830689, 12089.032877369966, 12246.203996740183, 12255.264684118982, 12600.747985040065, 10532.315399499008, 10744.946275268887, 11027.0, 11164.330140191614, 11231.101511878867, 10381.325213861075, 10450.895491044992, 10598.89401105979, 10744.247902647052, 10871.67384978452, 10827.404492752974, 10873.891261087289, 10883.891161088288, 11311.434428278388, 11317.943410282896, 7050.294970502909, 7283.963580182065, 7314.890276645961, 7389.556626602421, 7459.850802984019, 8427.536485493356, 9700.660476883557, 9747.853782193415, 9833.459159746282, 9868.703938881845], "value": 9002.241077761628, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.6991331820318, 1479.3786609623958, 1619.5303362025013, 1767.8143794901528, 1822.9088545572727, 7484.438667099976, 8107.108218096012, 8515.020772611151, 8533.061363250044, 8544.974603047644, 7673.117591476982, 7747.3027427531515, 8008.439409241356, 8248.958755206215, 8254.752357429272, 2275.2719129878446, 2305.5965206088867, 2309.503456756797, 2313.618252988255, 2313.826463015272, 2307.261676263596, 2331.1258278145697, 2351.27110595715, 2351.600227961242, 2354.9646755298686, 2027.9087441065187, 2087.5720477302193, 2234.2626933112033, 2281.2243837095384, 2282.0985710644286, 2286.4512516995883, 2286.965695514569, 2310.5032418030037, 2310.976890231093, 2311.110222564308, 2282.2468585366787, 2292.6790249365104, 2299.85050971688, 2303.8156947444163, 2304.665823455606, 8312.625931833021, 8336.749897503085, 8428.367872409593, 8435.536045517494, 8449.281811046007, 5020.3724534433195, 5175.120229560946, 5181.844544663666, 5183.429822719498, 5189.091908915962, 3188.6970737779875, 3308.139883630249, 3513.1744040150543, 3524.6299138590334, 3527.2592755521373, 1037.6783197208813, 1046.8220402531588, 1058.042471563229, 1074.6346242277664, 1091.4051841746268, 10903.509342079626, 11023.118150547936, 11026.889731102747, 11037.392943388111, 11050.668479945614, 3771.076086358805, 4542.909141817145, 4605.171069207575, 4625.820415794018, 4646.4888862225125, 9246.121618446237, 9459.290553208537, 9461.763455913657, 9483.241340692728, 9487.051294870596, 4545.318202269686, 5175.896482070338, 5182.274481572583, 5184.11869982104, 5187.948120518747, 4689.132510485571, 4699.459562150328, 4723.645726570521, 4740.525947405301, 4747.192977193886, 3838.213166300902, 3844.826982785781, 3864.961350386516, 3947.3684210526176, 3948.447217389568, 1141.6860363399965, 1246.840438392304, 1248.4257241668934, 1250.881166289201, 1266.8986481081492, 786.8662327404355, 873.3362644390309, 878.4948654523674, 901.833160865242, 958.2669258017622, 5515.61390702655, 5563.942850858405, 5673.978683836949, 5731.197632331477, 5820.592558520862, 855.2601999270711, 864.3776480933718, 897.8608315711075, 941.538646063424, 946.8106378724145, 3392.6090302975663, 3753.9624603753614, 3803.7907915064384, 3826.521684789401, 3934.6458818705883, 9749.902500975042, 10220.897791022144, 10229.846552301722, 10232.53953572091, 10239.84640230397, 10131.797364052825, 10146.985301469942, 10148.492575371201, 10169.288149829588, 10170.745731356626, 6979.89530157048, 7699.384049275933, 7786.688532458751, 7891.684332626745, 7898.01274840645, 5702.201691763239, 5743.3395159557185, 5857.238558987305, 5899.734511946886, 5978.461938425563, 9188.95405522968, 9334.626614935598, 9433.29250306216, 9577.329586928849, 9617.278704097082, 9540.427574345562, 9541.236701064037, 9655.131038206595, 9709.514524273616, 9793.363431376938, 7192.244814294424, 7235.782926512213, 7260.600666963368, 7334.193238743627, 7341.155767086853, 7713.537187768752, 7898.526088434713, 7900.170482099296, 7902.644381002984, 7927.84144317122, 7028.8594228114125, 7160.785176444715, 7188.676509556978, 7205.171405288459, 7287.599182045038, 7073.469489788389, 7095.254998225112, 7118.822029449305, 7125.821854453473, 7195.172555156224, 6621.5364924454825, 6768.221654509597, 6774.966125169342, 6801.625910574966, 6801.727930882709, 4832.806687732451, 4869.147899117711, 4873.342098816614, 4890.997345544226, 4932.087563800708, 4790.784414701417, 4821.662483626113, 4926.753662316942, 4968.130577148912, 4994.475580064186], "value": 4427.003037431227, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [238.8375904385018, 303.3523427482326, 309.5186984239508, 312.31446973892304, 315.36296680704993, 4315.74105553667, 4932.500262207635, 5103.438621751614, 5125.538701516867, 5167.132241605907, 4108.870060733299, 4276.893077673052, 4614.261718125101, 4956.529129732669, 4977.925331120027, 3221.4362486564874, 3880.6313400227004, 3904.0, 3915.099527108767, 3922.1564365834683, 3250.658680838513, 3904.0239940014994, 3905.1018265798884, 3917.314469967744, 3941.2314598653275, 3671.247394284179, 3817.5609804872515, 3867.400552914303, 3883.961160388388, 3910.8240129194114, 3525.6121826599056, 3850.3646898261754, 3850.4801851750076, 3859.286032084073, 3862.0, 3018.6717844148725, 3128.201284261536, 3337.4826901830256, 3343.966560334414, 3345.946027001511, 4924.138275801721, 4964.925526117111, 4966.975165124188, 4972.563443912457, 4984.551390374847, 3565.23347480293, 3749.212665340282, 4099.631033206996, 4275.87172384829, 4431.0, 5374.570034397278, 5379.838604841861, 5384.246205531229, 5388.838334849961, 5413.431589683066, 3692.2615476904725, 3700.9259814803822, 3708.1471261609713, 3712.201876596545, 3715.312667156558, 4629.606483448911, 5281.788728450857, 5529.447055294479, 5604.523615492688, 5607.8598035048635, 3133.4046531159006, 3133.6709645487354, 3731.4962480065387, 3736.954382178516, 3759.436084587334, 3773.4528493368307, 3793.241351729665, 3794.772313661189, 3801.4297855321925, 3823.5220597425323, 3860.942085868714, 4007.6393124618926, 4249.745015299092, 4379.255526560492, 4389.122175564899, 4422.3808666786035, 4768.427788665313, 5351.6918880300955, 5399.300349825087, 5404.351477822686, 1044.7179261599326, 1101.3061771084165, 1122.2985633978763, 1231.599730087725, 1233.9568115116108, 784.4587234807964, 789.8538770327508, 801.266840840636, 830.2983978538126, 853.6713365354383, 4226.640735537452, 4794.496577859344, 4796.880077998008, 4804.35141255926, 4853.4418541867435, 4946.629002824802, 5034.867154890194, 5239.1662917979165, 5254.290670759475, 5276.92084618731, 4084.571120032413, 4393.934850620203, 4461.079606773565, 4705.470722015359, 4797.95202047982, 2385.606374948114, 2419.334682962164, 2437.6465412515086, 2523.9747602524108, 2746.3134216445887, 4692.193505500339, 5081.4084816743725, 5155.432902380735, 5396.460353964572, 5411.648242864201, 4481.5070342262325, 4946.802127914915, 5086.821961231339, 5375.623706340597, 5384.9461505385225, 4936.3829521309835, 5154.948450515447, 5377.848860455849, 5399.487048730286, 5411.5129638331955, 4390.309730753619, 4730.219513780187, 4951.72765497901, 5247.5539579134675, 5277.8416647500635, 4362.520122786555, 4931.827386041471, 5118.616103792156, 5144.922826157535, 5171.896562068813, 4661.533846615312, 4848.260732686021, 5105.055564720538, 5387.434319396563, 5416.566674666096, 3009.593704849817, 3393.3043726035608, 3535.717142628532, 3548.717436371398, 3571.4642803579673, 4367.497737760198, 4435.043533867823, 4514.706544074625, 5125.79496820123, 5223.347081614798, 4476.529964353696, 5065.29085927963, 5072.391313042459, 5074.543291103819, 5094.885622466635, 4543.38664280318, 4751.6436267279405, 4873.1471992400475, 5024.799008039784, 5030.8239211627415, 3992.1456158846563, 4477.619402350837, 4500.88747781295, 4874.4150701916, 5144.536991670768, 5099.949000509948, 5320.8935821282585, 5359.785608575769, 5388.245645609696, 5423.078076727044, 4727.38543989293, 5275.417374787625, 5291.629585928948, 5296.629235953446, 5322.361316642027], "value": 3770.9526464560545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.232620628, 690.6098054599152, 706.6290197646232, 760.3764912771526, 792.932600728938, 14178.149311041345, 14545.418183272657, 14547.981641285101, 14559.854401455983, 14581.97926145169, 12906.096573239853, 12912.483500659962, 12913.483460661586, 13007.414666340013, 13039.543615973444, 657.8750037492873, 686.8488932434856, 689.48633268215, 822.3503432288514, 846.5344060766585, 831.2394159344193, 849.8342823149488, 850.1540966738073, 850.8851305073829, 857.5669287010052, 909.440693973205, 911.2983003087619, 911.8267529169469, 912.8448163812135, 914.4193437167413, 699.2657709405104, 724.9166345870187, 726.7855982485127, 731.6890321613346, 903.3631289940577, 539.5602583894114, 640.8013515810114, 664.7540410048265, 666.8066260784367, 668.9799306020778, 13948.58154255374, 13992.580222593338, 14035.78946315806, 14048.297585120703, 14068.0, 12727.87272127276, 12770.425330860133, 12933.224006559636, 13012.479500819956, 13046.086773925828, 11308.886911130949, 11683.76632467346, 11695.497931832797, 12082.395880205955, 12117.697057573543, 4183.572624019793, 4260.424842646234, 4363.759993200372, 4364.759938203398, 4374.49693285273, 77219.0, 80139.19860801376, 80210.59894700548, 81210.0, 82926.17073829245, 9625.422474651543, 9816.282150623612, 9859.753506162404, 10265.589376425009, 10415.68752937413, 18975.43073707791, 19220.213069104902, 19247.32634357819, 19254.133563989653, 19289.51776205578, 6659.4006539411685, 7201.819954501075, 7261.092363454568, 7445.7766267012075, 7505.812354691177, 3391.7117045051446, 4121.29937910556, 4166.0834616384345, 4190.994161401241, 4303.892402689895, 3214.630317513469, 3314.121757734202, 3316.8839090631714, 3326.8835590754206, 3354.2285274387027, 616.2820314333751, 623.7785586116954, 631.7599312261403, 636.4749082007362, 641.2433328672212, 587.8177764892897, 596.758312883283, 614.3119705929388, 623.488739233833, 628.9088082228051, 8405.201505856934, 8425.873611895826, 8527.317814574819, 8656.220940115294, 8690.695825646199, 533.8958903013953, 580.9564282678816, 598.4524160393279, 611.788932818177, 621.552482212806, 3064.969350306513, 4077.3883917412627, 4188.560201178893, 4205.579442055831, 4205.873823785291, 69636.65181674059, 85825.14174858296, 117925.0, 118713.0, 119905.20142197874, 56803.0, 63531.68234158892, 77619.61190194012, 86477.0, 94670.0532994675, 91109.54445227864, 91657.62513562436, 92419.0, 92636.0, 94398.0, 8032.518048917083, 8263.628136733741, 8338.62476188585, 8473.70042433089, 8498.065212826461, 47055.76472117617, 47725.04549909052, 51534.22698659598, 57678.7116064417, 60279.09581356195, 50908.49091509038, 52289.738551307, 68341.0, 69394.30605693879, 69560.0, 12116.27302361861, 12427.813582796447, 12556.937215313865, 12599.18105323151, 12644.620661380173, 13468.730625387634, 13519.431308051438, 13699.315034248448, 14110.50613228532, 14671.853281467049, 13001.609951701465, 13046.804297935349, 13519.256440895846, 13536.187828730308, 13601.727965440834, 12966.935165324114, 13105.672358191121, 13150.736985260433, 13681.315934203449, 13736.038477306492, 12484.375781211085, 12621.747565048465, 12934.353282336035, 12944.0, 13178.472861085449, 9564.808703825745, 10640.521176547229, 11030.393328367018, 11402.087832973508, 11510.53957841677, 9409.388389754644, 10552.736181595521, 10932.424860034575, 11006.0, 11090.833637495283], "value": 7055.360887936663, "unit": "score"}], "name": "dromaeo_css", "value": 6975.129467098064, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 113.0, 111.0, 114.0, 114.0, 115.0, 113.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 109.0, 146.0, 115.0, 141.0, 140.0, 142.0, 138.0, 109.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 196.0, 266.0, 203.0, 182.0, 184.0, 183.0, 183.0, 183.0, 185.0], "value": 194.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 89.0, 60.0, 81.0, 82.0, 82.0, 109.0, 58.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 95.0, 71.0, 68.0, 70.0, 89.0, 67.0, 69.0, 92.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 135.0, 84.0, 90.0, 130.0, 87.0, 83.0, 87.0, 83.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 91.0, 92.0, 93.0, 93.0, 93.0, 92.0], "value": 92.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 83.0, 82.0, 83.0, 82.0, 85.0, 85.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 54.0, 53.0, 56.0, 57.0, 58.0, 60.0, 56.0, 58.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 81.0, 76.0, 71.0, 70.0, 69.0, 69.0, 69.0, 70.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 84.0, 83.0, 82.0, 84.0, 84.0, 83.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 141.0, 134.0, 134.0, 140.0, 142.0, 142.0, 179.0, 142.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 214.0, 164.0, 165.0, 165.0, 166.0, 166.0, 160.0, 164.0, 164.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 69.0, 68.0, 67.0, 68.0, 66.0, 67.0, 67.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1454.1, "alertThreshold": 2.0}]} 20:30:17 INFO - Return code: 0 20:30:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:30:17 INFO - # TBPL SUCCESS # 20:30:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:30:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:30:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:30:17 INFO - 2017-01-17 20:30:17.280 screenresolution[1916:18120] starting screenresolution argv=screenresolution get 20:30:17 INFO - 2017-01-17 20:30:17.283 screenresolution[1916:18120] Display 0: 1600x1200x32@60 20:30:17 INFO - 2017-01-17 20:30:17.288 screenresolution[1917:18123] starting screenresolution argv=screenresolution list 20:30:17 INFO - Available Modes on Display 0 20:30:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:30:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:30:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:30:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:30:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:30:17 INFO - Intel Iris: 20:30:17 INFO - Chipset Model: Intel Iris 20:30:17 INFO - Type: GPU 20:30:17 INFO - Bus: Built-In 20:30:17 INFO - VRAM (Dynamic, Max): 1536 MB 20:30:17 INFO - Vendor: Intel (0x8086) 20:30:17 INFO - Device ID: 0x0a2e 20:30:17 INFO - Revision ID: 0x0009 20:30:17 INFO - Displays: 20:30:17 INFO - Display: 20:30:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:30:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:30:17 INFO - Display Serial Number: 1600x1200 60 20:30:17 INFO - Main Display: Yes 20:30:17 INFO - Mirror: Off 20:30:17 INFO - Online: Yes 20:30:17 INFO - Rotation: Supported 20:30:17 INFO - Return code: 0 20:30:17 INFO - Running post-action listener: _resource_record_post_action 20:30:17 INFO - [mozharness: 2017-01-18 04:30:17.532117Z] Finished run-tests step (success) 20:30:17 INFO - Running post-run listener: _resource_record_post_run 20:30:17 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 884736; Write bytes: 659659776; Read time: 14; Write time: 1947 20:30:17 INFO - TinderboxPrint: CPU usage
53.6% 20:30:17 INFO - TinderboxPrint: I/O read bytes / time
884,736 / 14 20:30:17 INFO - TinderboxPrint: I/O write bytes / time
659,659,776 / 1,947 20:30:17 INFO - TinderboxPrint: CPU idle
2,246.4 (46.4%) 20:30:17 INFO - TinderboxPrint: CPU system
972.6 (20.1%) 20:30:17 INFO - TinderboxPrint: CPU user
1,622.7 (33.5%) 20:30:17 INFO - TinderboxPrint: Swap in / out
350,633,984 / 0 20:30:17 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346323456; Write bytes: 345499136; Read time: 26796; Write time: 741 20:30:17 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 741376; Write bytes: 306980352; Read time: 9; Write time: 1174 20:30:18 INFO - Running post-run listener: _upload_blobber_files 20:30:18 INFO - Blob upload gear active. 20:30:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:30:18 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 20:30:18 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'] 20:30:18 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 20:30:18 INFO - (blobuploader) - INFO - Open directory for files ... 20:30:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:30:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:30:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:19 INFO - (blobuploader) - INFO - Done attempting. 20:30:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:30:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:20 INFO - (blobuploader) - INFO - Done attempting. 20:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:30:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:30:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:30:22 INFO - (blobuploader) - INFO - Done attempting. 20:30:22 INFO - (blobuploader) - INFO - Iteration through files over. 20:30:22 INFO - Return code: 0 20:30:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:30:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:30:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb6d500ffb2770d89d169579da04af010526f106cf360eb4d7259904b564a48b73296ba84bf56f14309ab06af480528ffe2d5f38d67bccd8310ac4ac49ac13f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4c1e83cd85fe2ff4f373a99733e58d863d535448479920d4c5289ec88331ef404168370dabd58bce220de222ca634836ce7c03b3585abbb6942e9cc72e965b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da46ce3a4a808b59806c51878bccc8979e785cc4fddfb86c2ba3a4ae4cb65896b992c831f6b867d3c397fea22c79af3a2970262980094809305b40561ced59e2"} 20:30:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:30:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:30:22 INFO - Contents: 20:30:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb6d500ffb2770d89d169579da04af010526f106cf360eb4d7259904b564a48b73296ba84bf56f14309ab06af480528ffe2d5f38d67bccd8310ac4ac49ac13f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4c1e83cd85fe2ff4f373a99733e58d863d535448479920d4c5289ec88331ef404168370dabd58bce220de222ca634836ce7c03b3585abbb6942e9cc72e965b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da46ce3a4a808b59806c51878bccc8979e785cc4fddfb86c2ba3a4ae4cb65896b992c831f6b867d3c397fea22c79af3a2970262980094809305b40561ced59e2"} 20:30:22 INFO - Running post-run listener: copy_logs_to_upload_dir 20:30:22 INFO - Copying logs to upload dir... 20:30:22 INFO - mkdir: /builds/slave/test/build/upload/logs 20:30:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.210856 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-01-17 20:30:22.465386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 20:30:22.469107) ========= 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.LK6H8chO1N/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.8FCPIsS8bu/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb6d500ffb2770d89d169579da04af010526f106cf360eb4d7259904b564a48b73296ba84bf56f14309ab06af480528ffe2d5f38d67bccd8310ac4ac49ac13f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4c1e83cd85fe2ff4f373a99733e58d863d535448479920d4c5289ec88331ef404168370dabd58bce220de222ca634836ce7c03b3585abbb6942e9cc72e965b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da46ce3a4a808b59806c51878bccc8979e785cc4fddfb86c2ba3a4ae4cb65896b992c831f6b867d3c397fea22c79af3a2970262980094809305b40561ced59e2"} build_url:https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011120 build_url: 'https://queue.taskcluster.net/v1/task/WRS42k9TSOiHyBjX0GdwaA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb6d500ffb2770d89d169579da04af010526f106cf360eb4d7259904b564a48b73296ba84bf56f14309ab06af480528ffe2d5f38d67bccd8310ac4ac49ac13f0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4c1e83cd85fe2ff4f373a99733e58d863d535448479920d4c5289ec88331ef404168370dabd58bce220de222ca634836ce7c03b3585abbb6942e9cc72e965b3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/da46ce3a4a808b59806c51878bccc8979e785cc4fddfb86c2ba3a4ae4cb65896b992c831f6b867d3c397fea22c79af3a2970262980094809305b40561ced59e2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-17 20:30:22.503518) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 20:30:22.503855) ========= 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.LK6H8chO1N/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.8FCPIsS8bu/Listeners TMPDIR=/var/folders/p7/w1dx92jj7lv8cq11sn2wgyk800000w/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.005226 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-17 20:30:22.552540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 20:30:22.552904) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-17 20:30:22.553214) ========= ========= Total master_lag: 0.19 =========