builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0122 starttime: 1461712618.98 results: success (0) buildid: 20160426142902 builduid: 94475adab2ed44fbb18e832244c7fdd0 revision: 937d57e8b4f0fa5f16231563ab0425eee9d40c82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 16:16:58.982543) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 16:16:58.982965) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-26 16:16:58.983250) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-04-26 16:17:01.534695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:01.535156) ========= 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.sFRPbWs9PP/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.iRBT2zKejh/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 16:17:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 77.7M=0s 2016-04-26 16:17:01 (77.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101780 ========= master_lag: 0.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:01.891886) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:01.892209) ========= 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.sFRPbWs9PP/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.iRBT2zKejh/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.027258 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:01.935532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 16:17:01.935876) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 937d57e8b4f0fa5f16231563ab0425eee9d40c82 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 937d57e8b4f0fa5f16231563ab0425eee9d40c82 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sFRPbWs9PP/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.iRBT2zKejh/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 16:17:02,011 truncating revision to first 12 chars 2016-04-26 16:17:02,011 Setting DEBUG logging. 2016-04-26 16:17:02,011 attempt 1/10 2016-04-26 16:17:02,011 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/937d57e8b4f0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 16:17:02,789 unpacking tar archive at: fx-team-937d57e8b4f0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.023258 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 16:17:02.975934) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:02.979263) ========= script_repo_revision: 937d57e8b4f0fa5f16231563ab0425eee9d40c82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:02.979657) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:02.979957) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 16:17:02.996355) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-04-26 16:17:02.996630) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sFRPbWs9PP/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.iRBT2zKejh/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:17:03 INFO - MultiFileLogger online at 20160426 16:17:03 in /builds/slave/test 16:17:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:17:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:03 INFO - {'append_to_log': False, 16:17:03 INFO - 'base_work_dir': '/builds/slave/test', 16:17:03 INFO - 'blob_upload_branch': 'Fx-Team', 16:17:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:03 INFO - 'branch': 'Fx-Team', 16:17:03 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:03 INFO - 'config_files': ('talos/mac_config.py',), 16:17:03 INFO - 'default_actions': ('clobber', 16:17:03 INFO - 'read-buildbot-config', 16:17:03 INFO - 'download-and-extract', 16:17:03 INFO - 'populate-webroot', 16:17:03 INFO - 'create-virtualenv', 16:17:03 INFO - 'install', 16:17:03 INFO - 'run-tests'), 16:17:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:03 INFO - 'download_minidump_stackwalk': True, 16:17:03 INFO - 'download_symbols': 'ondemand', 16:17:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:17:03 INFO - 'tooltool.py': '/tools/tooltool.py', 16:17:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:17:03 INFO - '/tools/misc-python/virtualenv.py')}, 16:17:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:03 INFO - 'installer_path': 'installer.exe', 16:17:03 INFO - 'log_level': 'info', 16:17:03 INFO - 'log_name': 'talos', 16:17:03 INFO - 'log_to_console': True, 16:17:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:17:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:17:03 INFO - 'opt_config_files': (), 16:17:03 INFO - 'pip_index': False, 16:17:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:17:03 INFO - 'cmd': ('bash', 16:17:03 INFO - '-c', 16:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:17:03 INFO - 'enabled': True, 16:17:03 INFO - 'halt_on_failure': False, 16:17:03 INFO - 'name': 'check_screen_resolution'},), 16:17:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:17:03 INFO - 'cmd': ('bash', 16:17:03 INFO - '-c', 16:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:17:03 INFO - 'enabled': True, 16:17:03 INFO - 'halt_on_failure': False, 16:17:03 INFO - 'name': 'check_screen_resolution'},), 16:17:03 INFO - 'run_cmd_checks_enabled': True, 16:17:03 INFO - 'sps_profile': False, 16:17:03 INFO - 'sps_profile_interval': 0, 16:17:03 INFO - 'suite': 'dromaeojs', 16:17:03 INFO - 'system_bits': '32', 16:17:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:03 INFO - 'title': 't-yosemite-r7-0122', 16:17:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:03 INFO - 'use_talos_json': True, 16:17:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:03 INFO - 'work_dir': 'build'} 16:17:03 INFO - ##### 16:17:03 INFO - ##### Running clobber step. 16:17:03 INFO - ##### 16:17:03 INFO - Running pre-action listener: _resource_record_pre_action 16:17:03 INFO - Running main action method: clobber 16:17:03 INFO - rmtree: /builds/slave/test/build 16:17:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:04 INFO - Running post-action listener: _resource_record_post_action 16:17:04 INFO - ##### 16:17:04 INFO - ##### Running read-buildbot-config step. 16:17:04 INFO - ##### 16:17:04 INFO - Running pre-action listener: _resource_record_pre_action 16:17:04 INFO - Running main action method: read_buildbot_config 16:17:04 INFO - Using buildbot properties: 16:17:04 INFO - { 16:17:04 INFO - "project": "", 16:17:04 INFO - "product": "firefox", 16:17:04 INFO - "script_repo_revision": "production", 16:17:04 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:17:04 INFO - "repository": "", 16:17:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:17:04 INFO - "buildid": "20160426142902", 16:17:04 INFO - "pgo_build": "False", 16:17:04 INFO - "basedir": "/builds/slave/test", 16:17:04 INFO - "buildnumber": 728, 16:17:04 INFO - "slavename": "t-yosemite-r7-0122", 16:17:04 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:17:04 INFO - "platform": "yosemite_r7", 16:17:04 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:17:04 INFO - "branch": "fx-team", 16:17:04 INFO - "repo_path": "integration/fx-team", 16:17:04 INFO - "revision": "937d57e8b4f0fa5f16231563ab0425eee9d40c82", 16:17:04 INFO - "stage_platform": "macosx64", 16:17:04 INFO - "builduid": "94475adab2ed44fbb18e832244c7fdd0", 16:17:04 INFO - "slavebuilddir": "test" 16:17:04 INFO - } 16:17:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:17:04 INFO - Running post-action listener: _resource_record_post_action 16:17:04 INFO - ##### 16:17:04 INFO - ##### Running download-and-extract step. 16:17:04 INFO - ##### 16:17:04 INFO - Running pre-action listener: _resource_record_pre_action 16:17:04 INFO - Running main action method: download_and_extract 16:17:04 INFO - mkdir: /builds/slave/test/build/tests 16:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:04 INFO - https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:17:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:17:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:17:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:17:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 16:17:07 INFO - Downloaded 1199 bytes. 16:17:07 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:17:07 INFO - Using the following test package requirements: 16:17:07 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:17:07 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:17:07 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:17:07 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'jsshell-mac.zip'], 16:17:07 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:17:07 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:17:07 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:17:07 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:17:07 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:17:07 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:17:07 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:17:07 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:07 INFO - https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:17:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:17:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:17:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:17:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:27 INFO - Downloaded 18114088 bytes. 16:17:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:17:30 INFO - Return code: 0 16:17:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:30 INFO - https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:17:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:17:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:17:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:17:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:31 INFO - Downloaded 18114088 bytes. 16:17:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:17:35 INFO - Return code: 0 16:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:35 INFO - https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:17:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:17:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:17:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:17:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:17:36 INFO - Downloaded 10840145 bytes. 16:17:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:17:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:17:36 INFO - Return code: 0 16:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:36 INFO - https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:17:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:17:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:17:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:17:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 16:17:39 INFO - Downloaded 110223501 bytes. 16:17:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:17:39 INFO - mkdir: /builds/slave/test/properties 16:17:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:39 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:39 INFO - Contents: 16:17:39 INFO - build_url:https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:17:40 INFO - Running post-action listener: _resource_record_post_action 16:17:40 INFO - Running post-action listener: set_extra_try_arguments 16:17:40 INFO - ##### 16:17:40 INFO - ##### Running populate-webroot step. 16:17:40 INFO - ##### 16:17:40 INFO - Running pre-action listener: _resource_record_pre_action 16:17:40 INFO - Running main action method: populate_webroot 16:17:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:17:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:17:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:17:40 INFO - '--tppagecycles', 16:17:40 INFO - '7'], 16:17:40 INFO - 'tests': ['tsvgm']}}, 16:17:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:17:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:17:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:17:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:17:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['damp', 'tps']}, 16:17:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'tests': ['damp', 'tps']}, 16:17:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['dromaeo_dom']}, 16:17:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['a11yr', 16:17:40 INFO - 'ts_paint', 16:17:40 INFO - 'tpaint', 16:17:40 INFO - 'sessionrestore', 16:17:40 INFO - 'sessionrestore_no_auto_restore', 16:17:40 INFO - 'tabpaint']}, 16:17:40 INFO - 'other-e10s': {'tests': ['a11yr', 16:17:40 INFO - 'ts_paint', 16:17:40 INFO - 'tpaint', 16:17:40 INFO - 'sessionrestore', 16:17:40 INFO - 'sessionrestore_no_auto_restore', 16:17:40 INFO - 'tabpaint']}, 16:17:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:17:40 INFO - 'ts_paint', 16:17:40 INFO - 'tpaint', 16:17:40 INFO - 'sessionrestore', 16:17:40 INFO - 'sessionrestore_no_auto_restore', 16:17:40 INFO - 'tabpaint']}, 16:17:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:17:40 INFO - 'ts_paint', 16:17:40 INFO - 'tpaint', 16:17:40 INFO - 'sessionrestore', 16:17:40 INFO - 'sessionrestore_no_auto_restore', 16:17:40 INFO - 'tabpaint']}, 16:17:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['a11yr', 16:17:40 INFO - 'ts_paint', 16:17:40 INFO - 'tpaint', 16:17:40 INFO - 'sessionrestore', 16:17:40 INFO - 'sessionrestore_no_auto_restore', 16:17:40 INFO - 'tabpaint']}, 16:17:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['a11yr', 16:17:40 INFO - 'ts_paint', 16:17:40 INFO - 'tpaint', 16:17:40 INFO - 'sessionrestore', 16:17:40 INFO - 'sessionrestore_no_auto_restore', 16:17:40 INFO - 'tabpaint']}, 16:17:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['tsvgx', 16:17:40 INFO - 'tsvgr_opacity', 16:17:40 INFO - 'tart', 16:17:40 INFO - 'tscrollx', 16:17:40 INFO - 'cart']}, 16:17:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:17:40 INFO - 'tsvgr_opacity', 16:17:40 INFO - 'tart', 16:17:40 INFO - 'tscrollx', 16:17:40 INFO - 'cart']}, 16:17:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'talos_options': ['--disable-e10s'], 16:17:40 INFO - 'tests': ['tp5o']}, 16:17:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'tests': ['tp5o']}, 16:17:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'talos_options': ['--disable-e10s', 16:17:40 INFO - '--xperf_path', 16:17:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:40 INFO - 'tests': ['tp5n']}, 16:17:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:40 INFO - 'talos_options': ['--xperf_path', 16:17:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:40 INFO - 'tests': ['tp5n']}}, 16:17:40 INFO - 'talos.zip': {'path': '', 16:17:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:17:40 INFO - Running post-action listener: _resource_record_post_action 16:17:40 INFO - ##### 16:17:40 INFO - ##### Running create-virtualenv step. 16:17:40 INFO - ##### 16:17:40 INFO - Running pre-action listener: _resource_record_pre_action 16:17:40 INFO - Running main action method: create_virtualenv 16:17:40 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:40 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 16:17:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:17:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:41 INFO - Using real prefix '/tools/python27' 16:17:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:17:41 INFO - Installing distribute.............................................................................................................................................................................................done. 16:17:44 INFO - Installing pip.................done. 16:17:44 INFO - Return code: 0 16:17:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:17: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', 'pip>=1.5'] in /builds/slave/test/build 16:17: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 pip>=1.5 16:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:44 INFO - 'HOME': '/Users/cltbld', 16:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:44 INFO - 'LOGNAME': 'cltbld', 16:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:44 INFO - 'MOZ_NO_REMOTE': '1', 16:17:44 INFO - 'NO_EM_RESTART': '1', 16:17:44 INFO - 'PAGER': '/bin/cat', 16:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:44 INFO - 'PWD': '/builds/slave/test', 16:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:44 INFO - 'SHELL': '/bin/bash', 16:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:17:44 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:44 INFO - 'USER': 'cltbld', 16:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:44 INFO - 'XPC_FLAGS': '0x0', 16:17:44 INFO - 'XPC_SERVICE_NAME': '0', 16:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:44 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 16:17:44 INFO - Cleaning up... 16:17:44 INFO - Return code: 0 16:17:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:17: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', 'psutil>=0.7.1'] in /builds/slave/test/build 16:17: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 psutil>=0.7.1 16:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:44 INFO - 'HOME': '/Users/cltbld', 16:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:44 INFO - 'LOGNAME': 'cltbld', 16:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:44 INFO - 'MOZ_NO_REMOTE': '1', 16:17:44 INFO - 'NO_EM_RESTART': '1', 16:17:44 INFO - 'PAGER': '/bin/cat', 16:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:44 INFO - 'PWD': '/builds/slave/test', 16:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:44 INFO - 'SHELL': '/bin/bash', 16:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:17:44 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:44 INFO - 'USER': 'cltbld', 16:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:44 INFO - 'XPC_FLAGS': '0x0', 16:17:44 INFO - 'XPC_SERVICE_NAME': '0', 16:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:45 INFO - Downloading/unpacking psutil>=0.7.1 16:17:45 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 16:17:45 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 16:17:45 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 16:17:45 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 16:17:45 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 16:17:45 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 16:17:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:48 INFO - Installing collected packages: psutil 16:17:48 INFO - Running setup.py install for psutil 16:17:48 INFO - building 'psutil._psutil_osx' extension 16:17:48 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 16:17:48 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 16:17:48 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 16:17:49 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 16:17:49 INFO - building 'psutil._psutil_posix' extension 16:17:49 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 16:17:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:17:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:17:49 INFO - ^ 16:17:49 INFO - 1 warning generated. 16:17:49 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 16:17:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:49 INFO - Successfully installed psutil 16:17:49 INFO - Cleaning up... 16:17:49 INFO - Return code: 0 16:17:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:49 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:17:49 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.0.0'] in /builds/slave/test/build 16:17:49 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.0.0 16:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:49 INFO - 'HOME': '/Users/cltbld', 16:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:49 INFO - 'LOGNAME': 'cltbld', 16:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:49 INFO - 'MOZ_NO_REMOTE': '1', 16:17:49 INFO - 'NO_EM_RESTART': '1', 16:17:49 INFO - 'PAGER': '/bin/cat', 16:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:49 INFO - 'PWD': '/builds/slave/test', 16:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:49 INFO - 'SHELL': '/bin/bash', 16:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:17:49 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:49 INFO - 'USER': 'cltbld', 16:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:49 INFO - 'XPC_FLAGS': '0x0', 16:17:49 INFO - 'XPC_SERVICE_NAME': '0', 16:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:17:49 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 16:17:49 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 16:17:49 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 16:17:49 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 16:17:49 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 16:17:49 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 16:17:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:17:52 INFO - Installing collected packages: mozsystemmonitor 16:17:52 INFO - Running setup.py install for mozsystemmonitor 16:17:52 INFO - Successfully installed mozsystemmonitor 16:17:52 INFO - Cleaning up... 16:17:52 INFO - Return code: 0 16:17:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:52 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 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:17:52 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 16:17:52 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 16:17:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:17:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:52 INFO - 'HOME': '/Users/cltbld', 16:17:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:52 INFO - 'LOGNAME': 'cltbld', 16:17:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:52 INFO - 'MOZ_NO_REMOTE': '1', 16:17:52 INFO - 'NO_EM_RESTART': '1', 16:17:52 INFO - 'PAGER': '/bin/cat', 16:17:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:52 INFO - 'PWD': '/builds/slave/test', 16:17:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:52 INFO - 'SHELL': '/bin/bash', 16:17:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:17:52 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:17:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:52 INFO - 'USER': 'cltbld', 16:17:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:52 INFO - 'XPC_FLAGS': '0x0', 16:17:52 INFO - 'XPC_SERVICE_NAME': '0', 16:17:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:53 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 16:17:53 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 16:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17:56 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 16:17:56 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 16:17:56 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 16:17:56 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 16:17:56 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 16:17:56 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 16:17:56 INFO - Downloading docopt-0.6.1.tar.gz 16:17:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:56 INFO - Running setup.py install for blobuploader 16:17:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:56 INFO - Running setup.py install for requests 16:17:57 INFO - Running setup.py install for docopt 16:17:57 INFO - Successfully installed blobuploader requests docopt 16:17:57 INFO - Cleaning up... 16:17:57 INFO - Return code: 0 16:17:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:57 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 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:17:57 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 16:17:57 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 16:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:57 INFO - 'HOME': '/Users/cltbld', 16:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:57 INFO - 'LOGNAME': 'cltbld', 16:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:57 INFO - 'MOZ_NO_REMOTE': '1', 16:17:57 INFO - 'NO_EM_RESTART': '1', 16:17:57 INFO - 'PAGER': '/bin/cat', 16:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:57 INFO - 'PWD': '/builds/slave/test', 16:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:57 INFO - 'SHELL': '/bin/bash', 16:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:17:57 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:57 INFO - 'USER': 'cltbld', 16:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:57 INFO - 'XPC_FLAGS': '0x0', 16:17:57 INFO - 'XPC_SERVICE_NAME': '0', 16:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:57 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-FydC0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ODQ9nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-czMQe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Os_R3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Wi7P4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-xNRpn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-XR5EvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-YrCn2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_nROHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-xyEbUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:58 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-mD4yoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-hxgKmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-ajebL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_QseS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-kEWpaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-dvVDiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:59 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-M1RCxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:17:59 INFO - Running setup.py install for manifestparser 16:17:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:17:59 INFO - Running setup.py install for mozcrash 16:17:59 INFO - Running setup.py install for mozdebug 16:18:00 INFO - Running setup.py install for mozdevice 16:18:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozfile 16:18:00 INFO - Running setup.py install for mozhttpd 16:18:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozinfo 16:18:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozInstall 16:18:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:00 INFO - Running setup.py install for mozleak 16:18:00 INFO - Running setup.py install for mozlog 16:18:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for moznetwork 16:18:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for mozprocess 16:18:01 INFO - Running setup.py install for mozprofile 16:18:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for mozrunner 16:18:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:01 INFO - Running setup.py install for mozscreenshot 16:18:01 INFO - Running setup.py install for moztest 16:18:02 INFO - Running setup.py install for mozversion 16:18:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:02 INFO - Cleaning up... 16:18:02 INFO - Return code: 0 16:18:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:02 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 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:18:02 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 16:18:02 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 16:18:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:18:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:02 INFO - 'HOME': '/Users/cltbld', 16:18:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:02 INFO - 'LOGNAME': 'cltbld', 16:18:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:02 INFO - 'MOZ_NO_REMOTE': '1', 16:18:02 INFO - 'NO_EM_RESTART': '1', 16:18:02 INFO - 'PAGER': '/bin/cat', 16:18:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:02 INFO - 'PWD': '/builds/slave/test', 16:18:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:02 INFO - 'SHELL': '/bin/bash', 16:18:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:18:02 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:18:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:02 INFO - 'USER': 'cltbld', 16:18:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:02 INFO - 'XPC_FLAGS': '0x0', 16:18:02 INFO - 'XPC_SERVICE_NAME': '0', 16:18:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-BSLj20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:02 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)) 16:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-rHKLvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-KoxLG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:02 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)) 16:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-sQVbjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:02 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)) 16:18:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:02 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-g6kibM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:03 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-_2wb_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:03 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-wbzDov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:03 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-N6fPxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:03 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-yzjFPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:03 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-zTJHxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-5RnPnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:03 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-8CxG82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-9bF4GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:03 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)) 16:18:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:03 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-Q7Uzs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:04 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-4O_Qyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:04 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)) 16:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:04 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-E9mR9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:18:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:04 INFO - Running setup.py (path:/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/pip-xK1CI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:04 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)) 16:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:04 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)) 16:18:04 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)) 16:18:04 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)) 16:18:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:04 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 16:18:04 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 16:18:04 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 16:18:04 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 16:18:04 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 16:18:04 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 16:18:06 INFO - Downloading blessings-1.6.tar.gz 16:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:06 INFO - Installing collected packages: blessings 16:18:06 INFO - Running setup.py install for blessings 16:18:07 INFO - Successfully installed blessings 16:18:07 INFO - Cleaning up... 16:18:07 INFO - Return code: 0 16:18:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:07 INFO - Reading from file tmpfile_stdout 16:18:07 INFO - Current package versions: 16:18:07 INFO - blessings == 1.6 16:18:07 INFO - blobuploader == 1.2.4 16:18:07 INFO - docopt == 0.6.1 16:18:07 INFO - manifestparser == 1.1 16:18:07 INFO - mozInstall == 1.12 16:18:07 INFO - mozcrash == 0.17 16:18:07 INFO - mozdebug == 0.1 16:18:07 INFO - mozdevice == 0.48 16:18:07 INFO - mozfile == 1.2 16:18:07 INFO - mozhttpd == 0.7 16:18:07 INFO - mozinfo == 0.9 16:18:07 INFO - mozleak == 0.1 16:18:07 INFO - mozlog == 3.1 16:18:07 INFO - moznetwork == 0.27 16:18:07 INFO - mozprocess == 0.22 16:18:07 INFO - mozprofile == 0.28 16:18:07 INFO - mozrunner == 6.11 16:18:07 INFO - mozscreenshot == 0.1 16:18:07 INFO - mozsystemmonitor == 0.0 16:18:07 INFO - moztest == 0.7 16:18:07 INFO - mozversion == 1.4 16:18:07 INFO - psutil == 3.1.1 16:18:07 INFO - requests == 1.2.3 16:18:07 INFO - wsgiref == 0.1.2 16:18:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:07 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 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:18:07 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 16:18:07 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 16:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:07 INFO - 'HOME': '/Users/cltbld', 16:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:07 INFO - 'LOGNAME': 'cltbld', 16:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:07 INFO - 'MOZ_NO_REMOTE': '1', 16:18:07 INFO - 'NO_EM_RESTART': '1', 16:18:07 INFO - 'PAGER': '/bin/cat', 16:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:07 INFO - 'PWD': '/builds/slave/test', 16:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:07 INFO - 'SHELL': '/bin/bash', 16:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:18:07 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:07 INFO - 'USER': 'cltbld', 16:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:07 INFO - 'XPC_FLAGS': '0x0', 16:18:07 INFO - 'XPC_SERVICE_NAME': '0', 16:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 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)) 16:18:07 INFO - Cleaning up... 16:18:07 INFO - Return code: 0 16:18:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:07 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 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:18:07 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 16:18:07 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 16:18:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:18:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:07 INFO - 'HOME': '/Users/cltbld', 16:18:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:07 INFO - 'LOGNAME': 'cltbld', 16:18:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:07 INFO - 'MOZ_NO_REMOTE': '1', 16:18:07 INFO - 'NO_EM_RESTART': '1', 16:18:07 INFO - 'PAGER': '/bin/cat', 16:18:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:07 INFO - 'PWD': '/builds/slave/test', 16:18:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:07 INFO - 'SHELL': '/bin/bash', 16:18:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:18:07 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:18:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:07 INFO - 'USER': 'cltbld', 16:18:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:07 INFO - 'XPC_FLAGS': '0x0', 16:18:07 INFO - 'XPC_SERVICE_NAME': '0', 16:18:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:07 INFO - Downloading/unpacking jsonschema 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:10 INFO - Downloading/unpacking functools32 (from jsonschema) 16:18:10 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 16:18:10 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 16:18:10 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 16:18:10 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 16:18:10 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 16:18:10 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 16:18:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:10 INFO - warning: no files found matching '*.txt' 16:18:10 INFO - no previously-included directories found matching 'build' 16:18:10 INFO - no previously-included directories found matching 'dist' 16:18:10 INFO - no previously-included directories found matching '.git*' 16:18:10 INFO - Installing collected packages: jsonschema, functools32 16:18:10 INFO - Running setup.py install for functools32 16:18:10 INFO - warning: no files found matching '*.txt' 16:18:10 INFO - no previously-included directories found matching 'build' 16:18:10 INFO - no previously-included directories found matching 'dist' 16:18:10 INFO - no previously-included directories found matching '.git*' 16:18:11 INFO - Successfully installed jsonschema functools32 16:18:11 INFO - Cleaning up... 16:18:11 INFO - Return code: 0 16:18:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:11 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f0ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f1e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa78cd4030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, '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.sFRPbWs9PP/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.iRBT2zKejh/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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 16:18:11 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', 'flake8'] in /builds/slave/test/build 16:18:11 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 flake8 16:18:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:18:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:11 INFO - 'HOME': '/Users/cltbld', 16:18:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:11 INFO - 'LOGNAME': 'cltbld', 16:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:11 INFO - 'MOZ_NO_REMOTE': '1', 16:18:11 INFO - 'NO_EM_RESTART': '1', 16:18:11 INFO - 'PAGER': '/bin/cat', 16:18:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:11 INFO - 'PWD': '/builds/slave/test', 16:18:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:11 INFO - 'SHELL': '/bin/bash', 16:18:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:18:11 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:18:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:11 INFO - 'USER': 'cltbld', 16:18:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:11 INFO - 'XPC_FLAGS': '0x0', 16:18:11 INFO - 'XPC_SERVICE_NAME': '0', 16:18:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:11 INFO - Downloading/unpacking flake8 16:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:18:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:18:13 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 16:18:13 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 16:18:13 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 16:18:13 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 16:18:13 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 16:18:13 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 16:18:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:18:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:18:14 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 16:18:14 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 16:18:14 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 16:18:14 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 16:18:14 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 16:18:14 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 16:18:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:18:14 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 16:18:14 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 16:18:14 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 16:18:14 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 16:18:14 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 16:18:14 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 16:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:18:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:18:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:18:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:18:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:18:15 INFO - no previously-included directories found matching 'docs/_build' 16:18:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:18:15 INFO - Running setup.py install for pep8 16:18:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:18:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:18:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:18:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:18:15 INFO - no previously-included directories found matching 'docs/_build' 16:18:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:18:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:18:15 INFO - Cleaning up... 16:18:15 INFO - Return code: 0 16:18:15 INFO - Running post-action listener: _resource_record_post_action 16:18:15 INFO - Running post-action listener: _start_resource_monitoring 16:18:15 INFO - Starting resource monitoring. 16:18:15 INFO - ##### 16:18:15 INFO - ##### Running install step. 16:18:15 INFO - ##### 16:18:15 INFO - Running pre-action listener: _resource_record_pre_action 16:18:15 INFO - Running main action method: install 16:18:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:15 INFO - Reading from file tmpfile_stdout 16:18:15 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:15 INFO - Reading from file tmpfile_stdout 16:18:15 INFO - Output received: 16:18:15 INFO - Usage: mozinstall [options] installer 16:18:15 INFO - Options: 16:18:15 INFO - -h, --help show this help message and exit 16:18:15 INFO - -d DEST, --destination=DEST 16:18:15 INFO - Directory to install application into. [default: 16:18:15 INFO - "/builds/slave/test"] 16:18:15 INFO - --app=APP Application being installed. [default: firefox] 16:18:15 INFO - mkdir: /builds/slave/test/build/application 16:18:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:18:49 INFO - Reading from file tmpfile_stdout 16:18:49 INFO - Output received: 16:18:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:18:49 INFO - Running post-action listener: _resource_record_post_action 16:18:49 INFO - ##### 16:18:49 INFO - ##### Running run-tests step. 16:18:49 INFO - ##### 16:18:49 INFO - Running pre-action listener: _resource_record_pre_action 16:18:49 INFO - Running main action method: run_tests 16:18:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:18:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:18:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:18:49 INFO - 2016-04-26 16:18:49.827 screenresolution[1640:7532] starting screenresolution argv=screenresolution get 16:18:49 INFO - 2016-04-26 16:18:49.830 screenresolution[1640:7532] Display 0: 1600x1200x32@60 16:18:49 INFO - 2016-04-26 16:18:49.836 screenresolution[1641:7535] starting screenresolution argv=screenresolution list 16:18:49 INFO - Available Modes on Display 0 16:18:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:18:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:18:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:18:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:18:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:18:50 INFO - Intel Iris: 16:18:50 INFO - Chipset Model: Intel Iris 16:18:50 INFO - Type: GPU 16:18:50 INFO - Bus: Built-In 16:18:50 INFO - VRAM (Dynamic, Max): 1536 MB 16:18:50 INFO - Vendor: Intel (0x8086) 16:18:50 INFO - Device ID: 0x0a2e 16:18:50 INFO - Revision ID: 0x0009 16:18:50 INFO - Displays: 16:18:50 INFO - Display: 16:18:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:18:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:18:50 INFO - Display Serial Number: 1600x1200 60 16:18:50 INFO - Main Display: Yes 16:18:50 INFO - Mirror: Off 16:18:50 INFO - Online: Yes 16:18:50 INFO - Rotation: Supported 16:18:50 INFO - Return code: 0 16:18:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:50 INFO - Python 2.7.3 16:18:50 INFO - Return code: 0 16:18:50 INFO - grabbing minidump binary from tooltool 16:18: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:50 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106dade00>, '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 0x106bd6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106da8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:50 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 16:18:50 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 16:18:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:50 INFO - Return code: 0 16:18:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:18:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:18:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:18:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:18:51 INFO - Return code: 0 16:18:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 16:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0122 --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 16:18:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sFRPbWs9PP/Render', 16:18:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:51 INFO - 'HOME': '/Users/cltbld', 16:18:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:51 INFO - 'LOGNAME': 'cltbld', 16:18:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:51 INFO - 'MOZ_NO_REMOTE': '1', 16:18:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:51 INFO - 'NO_EM_RESTART': '1', 16:18:51 INFO - 'PAGER': '/bin/cat', 16:18:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:51 INFO - 'PWD': '/builds/slave/test', 16:18:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:51 INFO - 'SHELL': '/bin/bash', 16:18:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iRBT2zKejh/Listeners', 16:18:51 INFO - 'TMPDIR': '/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/', 16:18:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:51 INFO - 'USER': 'cltbld', 16:18:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:51 INFO - 'XPC_FLAGS': '0x0', 16:18:51 INFO - 'XPC_SERVICE_NAME': '0', 16:18:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0122', '--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 16:18:51 INFO - mozversion application_buildid: 20160426142902 16:18:51 INFO - mozversion application_changeset: 937d57e8b4f0fa5f16231563ab0425eee9d40c82 16:18:51 INFO - mozversion application_display_name: Nightly 16:18:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:51 INFO - mozversion application_name: Firefox 16:18:51 INFO - mozversion application_remotingname: firefox 16:18:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:18:51 INFO - mozversion application_vendor: Mozilla 16:18:51 INFO - mozversion application_version: 49.0a1 16:18:51 INFO - mozversion platform_buildid: 20160426142902 16:18:51 INFO - mozversion platform_changeset: 937d57e8b4f0fa5f16231563ab0425eee9d40c82 16:18:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:18:51 INFO - mozversion platform_version: 49.0a1 16:18:51 INFO - using testdate: 1461712731 16:18:51 INFO - actual date: 1461712731 16:18:51 INFO - starting webserver on 'localhost:49270' 16:18:51 INFO - SUITE-START | Running 2 tests 16:18:51 INFO - TEST-START | dromaeo_css 16:18:51 INFO - operating with platform_type : mac_ 16:18:51 INFO - Initialising browser for dromaeo_css test... 16:18:51 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpsX_5DJ/profile http://localhost:49270/getInfo.html) 16:18:53 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 16:18:53 INFO - PROCESS | 1659 | colorDepth:24 16:18:53 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 16:18:53 INFO - PROCESS | 1659 | __metrics 16:18:54 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:18:54 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:18:54 INFO - TEST-INFO | 1659: exit 0 16:18:54 INFO - Browser initialized. 16:18:54 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:54 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmpsX_5DJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:06 INFO - PROCESS | 1661 | RSS: Main: 239128576 16:19:06 INFO - PROCESS | 1661 | 16:19:58 INFO - PROCESS | 1661 | 1461712798266 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:22:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 16:22:10 INFO - PROCESS | 1661 | RSS: Main: 370253824 16:22:10 INFO - PROCESS | 1661 | 16:25:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 16:25:14 INFO - PROCESS | 1661 | RSS: Main: 382357504 16:25:14 INFO - PROCESS | 1661 | 16:28:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 16:28:17 INFO - PROCESS | 1661 | RSS: Main: 378359808 16:28:17 INFO - PROCESS | 1661 | 16:31:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 16:31:19 INFO - PROCESS | 1661 | RSS: Main: 377917440 16:31:19 INFO - PROCESS | 1661 | 16:34:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 16:34:23 INFO - PROCESS | 1661 | RSS: Main: 341311488 16:34:23 INFO - PROCESS | 1661 | 16:37:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 16:37:27 INFO - PROCESS | 1661 | RSS: Main: 387084288 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | __start_tp_report 16:37:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 16:37:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 16:37:27 INFO - PROCESS | 1661 | |i|pagename|runs| 16:37:27 INFO - PROCESS | 1661 | |0;dojo.html;2771.3764403009327;3437.192259818942;3454.2400671852215;3454.412749832529;3508.403571392863;5044.72254026028;5252.579793616511;5587.469190426903;5667.178259152425;5691.7723291068305;5635.718214089289;5686.488216060554;5691.8577035574135;5718.571107166958;5721.5708821838425;5131.589472842164;5133.178691409385;5157.252198431225;5186.948130518703;5217.58259339252;5202.687838729669;5225.660332078422;5227.73861306935;5229.294045303893;5232.8691782705355;5120.564751996085;5183.792648294083;5185.844424667266;5194.246834209038;5207.557357624588;5144.845654630367;5218.191180367049;5218.191180367049;5226.111561034595;5279.736013199363;5038.647294689373;5085.821996230115;5142.202958541432;5157.71632560209;5164.483551644806;5593.105103183471;5605.691686957216;5669.348024977139;5686.914696279559;5744.8851022979725;6199.393721830024;6235.095911092866;6287.434130928193;6309.652969086698;6320.399562041599;6293.559450838443;6300.968495157541;6307.5269354798575;6330.082138089997;6337.904931426032;5734.942650573483;5793.391693872083;5800.0719884818645;5817;5922.822315330547;6254.34329395416;6330.873382532323;6340.397662222082;6347.841303967345;6378.77674281398;6084.30030546484;6095.908561371583;6103.298120716175;6125.356837532084;6155.076738489173;6121.8493044937295;6207.96896015517;6240.625562466266;6248.094026366151;6301.936980630227;6236.812895613139;6255.6872156392;6278.372162783685;6288.245410550671;6301.6534090625455;6260.937390626126;6297.118403423525;6310;6329.240491141092;6329.240491141092;4805.639577031667;4904.362432883774;4931.408231012229;4938.037082768826;4979.726115063706;4429.180601588717;4454.331850222427;4458.197524445631;4493.460784705856;4510.391097201901;5709.714514274269;5741.1388291756575;5744.569157313218;5749;5754.625949313365;5183.222516622537;6123.099607468126;6265;6315.842103947438;6337.7781777637565;5647.350554686182;5668.433156684298;5701.486866182064;5704.543636509069;5706.942930570641;5344.759485823147;5382.058139825514;5398.13629819227;5400.378956419983;5401.216823560561;6251.624902505864;6261.530385221035;6293.527985401113;6317.96841015792;6343.365663433619;6261.592996455216;6286.119943207863;6307.432331090224;6309.1167236587835;6312.684365781601;6297.748090076529;6304.464120549805;6319.652419116809;6327.96836015817;6328.145700330397;6074.271087469532;6101.03536932808;6146.6170111724505;6166.537509686881;6179.3820617937845;6184.041473571604;6206.813795586139;6219.782307619212;6229.844253893689;6233.345498722751;5914.733836977261;5945.821625351246;5948.196993406008;5997.070454079625;6006.729697163725;5451.182322651475;5616.297962754656;6060.424259695322;6075.149479072845;6080.7567697291615;5901.29184497863;5958.731857066529;5961.850953726192;5963.70181490915;5966.373530779206;6090.484362135808;6092.876921702668;6116.785912493041;6117.418845209698;6118.60229085108;5836.445537673914;5926.822195334147;5927.703614819328;5933.1396947443245;5939.227900372924 16:37:27 INFO - PROCESS | 1661 | |1;ext.html;502.76118843549295;549.648225135913;596.4572239262272;604.2658170323062;605.9244840408275;28097;28618.427631447386;28642.856785716067;36560.81719591398;37057.25885482289;40942.59057409425;41407;41489.58510414895;41529.96175095624;41740.58259417405;9351.719448416541;9605.327627066108;9608.471534065624;9619.519024048805;9625.085616866421;8158.388120890927;8357.080721120672;9394.530273486333;10966.958138976785;11216.66350009501;11077.50151243196;11171.218014738972;11185;11194.608188713397;11197.048250898684;8177.754667359989;11117.444127793618;11280.210385273034;11289.774204515905;11295.322280663186;10871.565137394495;11140.220184587;11151.665450036511;11152.442377881154;11154.553817847276;35999.82000090009;36595.268094637955;36646.450303245474;36657.26685466302;36700;3186.1078897908624;3190.4735718606403;3266.9183270418193;3338.5159151923076;3522.6125126236093;26880.59679104815;27448.725512744815;27813.721862781316;27993.160205193875;28894.844206231726;2643.47130573886;2760.7791376689815;2774.556071028646;2791.316127548743;2878.9856050719613;365822;367215;368928;369377;370738.29261707305;56986;57297.71351143301;57693;57730.711346443;57885.13172302419;60451.395486045454;60661.09008364878;60696.696516518015;60857.782844343754;60967;16069.035857848567;16353.018818870905;16363.100029498491;16371.672566548603;16390.180490975403;11021.944890275658;11234.3259404436;12765.744685106245;12819.230846149261;12839.743205135845;14967.401303947938;15123.092614442949;15140.545783626509;15207.695846083016;15371.308291126927;5557.694326812064;6660.333966603398;6675.966620166965;6748.021536877125;6764.526483146133;955.9426434413957;1008.594502112467;1026.6817286641165;1091.307020042279;1132.3404339484478;28173.732182051855;28257;29014.41971160565;29079.27301817429;29281.707182928323;1070.7323169207698;1152.7175841918784;1155.3472288157163;1163.3834067944003;1173.4602083041725;14494.782578261334;14616.976811623297;14924.776128358084;14948.701025979419;14984.250787460584;478181;482622;483080;484914;485678;53865.1920221197;54461.72769136208;54500;54703;54747.45252547503;1306.5884246462333;1400.5658245943587;1441.2505497141638;1494.3126161965617;1508.630385555546;27689.723102768716;28035;28618;29155.125346239645;29428.411431771674;45898.85252868705;46302;46862.76568617135;46915.06169876515;47054.76472617615;355935;357742;357807;358304;358331.2083439566;29974.850125750105;35222.29555408929;35781;36122.27755444929;36556.26887462183;39873.60126398699;41022;42950.78524607357;42965.57034429616;44211.55788442203;34545.654543455246;35846;36355;36485.63514364822;36703.760360738204;34667;35119.12202194965;35161;35831.64168358283;36044.91865244047;34737.30525389427;35002.824985874904;35381.82309088438;35557.1110722234;35683.82158089193;30271.697283026886;31628.05115846528;31635.683643163273;31709.8414507926;32180;21654.025568849756;23242.535149296582;23613.52772944497;23795.524089518458;24129.39676508031 16:37:27 INFO - PROCESS | 1661 | |2;jquery.html;397;401.38187191724745;407.0698454032538;407.7594219410547;417.99164016719664;13340.866591334083;19236;19760.01199940001;19762.110705018273;19791.307304244354;14204.431822727105;14852.257387130654;15151.848481515182;19249.903750481262;19941;5641.379448260698;5878.235829342179;5883.441073098049;5885.969955257834;5886.381929897363;4876.609871210295;4970.925436118461;5758.654480731169;5766.509846663039;5837.824865254028;4075.470188875442;4314.590113939171;5965.701714914237;6307.337729538402;6315.5894866833505;6242.313345531988;6291.087792270118;6300.527460440462;6330.240371155444;6351.237851457809;4862.416510018785;5413.783448662089;5514.889702205974;5628.690422026787;5633.295838020247;20097.58828940522;21184.576308473745;25246.873765631237;25294.494110117696;25640.230793076236;2023.4132101690639;2087.749470063587;2142.271627646592;2224.788645078715;2757.820741651806;14282.3572939218;14339.71320573593;14418.927905360511;15325.770113448307;15347.846521534864;1670.2901266961615;1771.8759686821927;1792.9193186306647;1812.909354532268;1872.4944265048498;111613;119881.40059299648;119917;120318;120839;9694.127528522326;11476.885231147582;11537.596184133516;11645.53417863293;11651.883481165249;14912;14966.850331496764;14968.625784355261;14981.550553483195;14998;7548.207438219018;8559.186877246651;8612.741617751477;8623.5257060861;8719.564021798884;7676.193999629959;8245.752627421187;8449;8573.485590864568;8580.699675511327;4565.543445655408;4643.953560464352;4658.580727734452;4664.183767840615;4664.346991421204;1780.4213630570116;1910.1117980139024;1970.7930667280016;1971.2706298669584;1998.6702194138095;752.6424948149585;823.4853216739538;947.5309721687809;982.6167794560128;989.9950500247453;19381.709274360896;19906;20034.098465568793;20159.798402015793;20487.18051277962;853.185208126238;921.5300196899495;979.4074584876047;979.5983646704817;985.9014098590227;10550.103241224582;10680.145588352912;10731.624393146203;10750.892491075145;10752.462376881125;480010;509857.76066795137;536539;545905;546755;100249;111016.4449177765;112448.4377578107;112785;115916;1003.7440452684518;1022.7136401807505;1055.2507719519228;1056.529844219315;1058.5871510111062;17054.31782728677;17542.298308067533;17805.376811811526;17850.732239016164;18038.188281527626;49153.75423122862;52038.69903252449;52296.21555676592;52355.73822130865;52605.7369713149;74304;74436;74768;75087;76888.6155569241;7703.768886933401;7827.373810095065;7939.6030198491;7990.320822730133;8297.543635100128;10160.13968570716;10180.185585153318;12089.75820483603;12113.394330283274;12311.692207694521;10524.947375263075;10648.467576621293;10791.892081079088;10852.728681783019;10863.293885897392;10288.434136122585;10336.483175841327;10439.90381009983;10691.679249622524;10712.035916767529;10706.357618542912;10757.946210269212;10962.835557466486;11056.391898445663;11121.110311174925;6501.382368674968;7825.295723384923;8069.515829050276;8126.967705996879;8229.917700822914;10132.949335253277;10632.362058276529;10739.46302684847;10953.83569246446;10972 16:37:27 INFO - PROCESS | 1661 | |3;mootools.html;1415.9929200353997;1585.0964949978509;1588.0074953154278;1846.6214426042663;1983.3157560641582;7845.137034926153;8507.064222935473;8741.950965884103;8742.388032837704;8756.562171891412;8354.456960297577;8632.482051076924;8643.611037503311;8646.697365592205;8676.045634980148;2399.6160614301716;2399.8800059997016;2407.3981504623844;2436.8294219404647;2437.731849496554;2136.2202795979433;2407.145463360508;2438.743931887153;2451.2891261534132;2456.680631517909;2416.2871952773976;2438.6951631046118;2447.4248551590363;2459.950800983979;2462.91379801707;2399.244238065004;2423.987880060597;2434.914777982762;2436.9756302436926;2438.7561243875557;2380.571497130516;2407.4944261705064;2409.6746939163163;2412.3124909400913;2417.4439878827966;8462.576871156418;8505.574721263913;8670.046294907555;8674.653013879437;8676.436031657986;5166.380034395859;5383.838484845461;5394.541463975567;5394.91907621386;5438.619296649236;3468.271662950783;3556.484309775094;3623.9094022649383;3631.4189729643394;3638.818059097061;1011.044562888071;1049.648367796789;1054.2356791326304;1061.692109288313;1068.8984546468073;11337.603183888525;11391.974722274952;11549.6535103947;11551.422428878523;11555.133364997659;4556.225441674923;4559.566841150086;4703.176944034849;4704;4711.811527538928;9061.13919177677;9074.228690561376;9092.499912504876;9111.498867562348;9116.316276279305;5222.503862133091;5288.8413347599635;5293;5296.390915044819;5308.867278318073;4425.933610995838;4587.403637527167;4782.139214941344;4837.758112094381;4952.306677065214;3228.257500774835;3699.3341198583976;3718.8326525306425;3812;3816.713746469026;1181.3561608922985;1216.2945491614842;1219.4939100273464;1226.3868065967017;1227.8403807505147;661.8279247395711;785.1520358013332;862.1034124510564;902.3638334973828;936.1318121191397;5604.523615492647;5723.370429252779;5823;5939.227900372924;6047.092936059538;898.4160295807777;915.789368445248;928.5450129436528;933.3979583168818;959;3390.288039511681;3604.9005053458945;3806.8286927088348;3910.432987216838;3980.144268982183;10062.899371006342;10607.363558186533;10684.09185219265;10690.30513016667;10696.679099627023;9881.25890558211;10503.949605039435;10625.362478251329;10628.840567391495;10656.9875861793;6512.967435162888;8107.878381824277;8117.756467305991;8198.549079800548;8210.30212431938;5784.768609255582;5801.883962320814;5893.9685555026845;6023.789167379121;6136.969315153396;9601.759956001157;9673;9685.273604479551;9855.11303982645;10026.248031397528;8562.48625082497;8858.822823543622;9613.423194608346;9959.651412200828;9967.152792012761;7280.1991780903;7296.817579560554;7454.030975973089;7461.104667439942;7516.5114267572435;7992.440529162903;8119.594020298843;8184.2224988625985;8199.344052475668;8200.179982001751;6129.417705317987;7271;7436.309842934008;7465.1788303287685;7559.055118110236;7131.893021604784;7227;7294.817629559304;7358.632068396666;7384.150822655463;6927.792166235021;6935.410490108397;7019.403350715246;7027.789166325018;7034.964825176046;4741.2651039089;4786.95213047865;4821.662483626113;4876.512348765237;5014.84955451337;4610.907781844277;4632.444106707217;4642.419697537808;4696.765161741968;4827.2035114207165 16:37:27 INFO - PROCESS | 1661 | |4;prototype.html;318.1156385249007;320.52242159182816;325.04436955351264;326.46948708348935;330.57686161712985;4467.709598876071;5228.973855130719;5328.200769884515;5362.4637536246355;5400.621956463049;4456.910861782762;4696.530346965302;5360.9463905360935;5374.865628359293;5406.9456455991;3897.9670387347446;4033.818478168475;4041;4048.4736984192014;4070;3259.722923551501;3771.18919432322;4081.9183616327655;4134.710570260083;4139.799458157131;4000.9799951000205;4017.598240175974;4041.979790101045;4067.1662309226685;4091.7340372875815;3853.344931361675;4062.7968601569805;4075.551689314188;4079.51045874494;4093.6111069448357;2933.559966005095;3516.894493165209;3527.4532447470683;3543.858245670196;3559.14580500678;4389.253826849444;4458.929856834369;5189.195674670432;5245.895082098336;5264.184051472028;3850.441685955521;4080.755154690728;4111.054457474797;4665.953340466586;4687.484376718558;4976.701397916137;5637.689927054011;5686.715664216814;5688.402717714663;5707.800226992035;3327.0536736009462;3862.2275544890854;3878.8836334909997;3898.3567711327596;3909.882703518899;5664.49019588239;5941.821745347646;5969.283685957669;6000.879982400327;6015.909761353583;3782.7730336179916;3864.555576108727;3923.7645741255615;3938.877419935362;3945.6054394560315;3533.717302615785;3853.2293541291856;3917.6484112980975;4000.299947509174;4002.298448547219;4076.7146299758733;4653.324803070892;4679.4618618858585;4683.370387949367;4701.717896926127;4593.770311484479;4958.603311735053;5708.431205957253;5726.398728133569;5758.395368486333;1247.7255003899127;1255.9763792509075;1389.3678376338944;1458.3801884199386;1466.955991320262;900.4192295969063;915.4461550761694;950.6435086842434;971.7036303927229;978.2516374973151;4754.762261886892;5360.892782144413;5655.115890895673;5678.5803549112725;5873.177755114202;4913.410390753133;6435.903461448082;6502.414782669583;6503.382178693017;6527.89025865604;5167.870803229949;5537.169424586345;5764.682942438206;5804.82585522435;5863.355030946593;2872.9281767955968;3091.335362896988;3300.9436980166597;3398.218409765718;3426.2119712466274;5559.388467268597;6224.087117238641;6430.549861509743;6711.530192886548;6851.2806155353965;5346;6552.312007239267;6636.407262257024;6666.899996500057;6688.39804417595;5839.270091238595;6023.006203976295;6578.473722102316;6686.331366863372;6784.932150678529;5159.664621799571;5661.320641523043;5828.7959921404245;6000.159977603226;6001.459868611846;4934.925976110289;4937.382827146606;5159.355080614923;5169.4830516948;5406.513413792777;5945.910811337747;6104.572679912363;6159.322474527888;6208.720607572761;6245.875082498415;3940.271049855786;3940.684745220433;3945.605439456146;3954.62431069048;4050.4126901599693;4973.35346404965;5246.029484545371;5886.6754980130845;5910.876933382644;5953.851153721192;5002.049610573979;5406.8648283791645;5479.260299859628;5779.7110144493445;5798.304203495608;5241.580673546031;5726.024521540062;5753.1370294456165;5773.653580785166;5775.48020678141;5084.923516397869;5185.533302002837;5201.115810312181;5493.642913210629;5517.862053448696;5123.897522049464;5325.547328477122;5350.491703288181;5653.491185793298;5838.474537291664;4979.311171967097;5202.505761952609;5644.604877658525;5686.516646085129;5716.456936591017 16:37:27 INFO - PROCESS | 1661 | |5;yui.html;608.6013661052011;725.4812808841681;738.3760722189747;741.6773703439003;748.7566540874213;12497.625071247865;14711.337989790458;15163;15211.239438028097;15224.771628425582;13772.586822395344;13817.654558636039;13898.305084745773;13922.1646701198;13933.512327068554;723.1322413104269;745.083547236899;870.6517393042802;910.5583791860959;928.0997432490474;909.3225546967518;910.6721580231111;913.9314551408638;914.6615752171706;915.3638221436105;963.02734238419;964.3731574476611;964.4502633498909;966.3815158298695;966.5891995901713;752.2702978111223;780.4302858913026;780.878963760618;781.0939310399909;958.6740508227201;698.078536332042;723.0889079759488;725.6843273176186;726.259215600085;726.2737262737263;12153.756924861453;12736.172148810298;14367.209803460806;14827.77758333626;14840.406383744637;12177.391130443442;12449.377531123408;12656.240625562403;12673;12673.429695663717;9637.855432168522;9973.90026099737;9974.551145198484;10017.549210285555;10020;4007.2586571484076;4014.3978403239457;4019.537753158366;4172.395002724618;4344.218040752663;74972;77828.61085694535;77847.66457006242;77853.61073194654;77882;9380.390274632127;9446.811063778687;9855.211583073336;9936.80126397468;9959.900400995897;21078.894605526875;21122.683159752614;21156.78843211579;21164.894175529025;21180.682289765668;7525.8118547035665;7689.9615501922135;7904.525728456311;7967.123616402191;7978.3218426434405;4124.773137477468;4228.9788551057045;4240.703150779415;4290.227759003347;4295.978520107379;3448.189675426273;3514.9121271968406;3520.0143959691327;3524.665156810099;3525.9294814104087;639.880209633142;643.8551325951623;658.3745441830281;660.3932612123136;661.9635920024444;534.5349545895018;540.6134613751187;552.4613501835759;573.2347316332659;597.9581429299994;8149.6630303990605;8155.918440815634;8261.17388261181;8594.527300998505;8602.35482338827;509.706918521844;579.5827004556712;580.3007376111775;601.6841158391792;628.2963081348919;3800.8289626966853;3862.864799731996;3900.4344370066183;3932.2528719543197;4129.380592911028;77464.22535774682;91982.08017919735;97088.51455742677;97458;100732.43774279121;66782.34142230394;80475.59762201269;83766;83927.16072839315;85125;75150.62424687842;75345.62327188438;75992.24007759852;76271;76332.45844269467;6006.939930600638;7739.496932699357;7954.761357159295;8012.799680007814;8101.5002962748285;45197.12437383119;46188.53811461842;46309.53690463052;46354.98692584971;46843.29735053909;62699;63706;63719;64880.026799598985;66870;11126;11201.271917325348;11204;11276.7744645105;11372.54509819598;11429.199956003;12017.759644807229;12018.519259229533;12097.455614497192;12268.141230113806;11734.647960561197;11736.178467507192;11773.941130294294;11954.701132471759;11984.099088618317;11565.363904985306;11570.329795506292;11690.4739286734;11853.525858965544;11898.643040708792;10645.94677026641;10747.301425407324;11095.278806877526;11248.887511124783;11752.11859110553;8354.916450835413;9192.632294708137;9775.657851975147;9898.20814334837;10038.749031274276;9605.327627066039;10028.162846935471;10052.145567626834;10055.721649996442;10076.510903426792 16:37:27 INFO - PROCESS | 1661 | __end_tp_report 16:37:27 INFO - PROCESS | 1661 | __start_cc_report 16:37:27 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1281 16:37:27 INFO - PROCESS | 1661 | __end_cc_report 16:37:27 INFO - PROCESS | 1661 | __startTimestamp1461713847233__endTimestamp 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | ------- Summary: start ------- 16:37:27 INFO - PROCESS | 1661 | Number of tests: 6 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5725.74 Median:5927.70 stddev:653.23 (11.0%) stddev-sans-first:612.74 16:37:27 INFO - PROCESS | 1661 | Values: 2771.4 3437.2 3454.2 3454.4 3508.4 5044.7 5252.6 5587.5 5667.2 5691.8 5635.7 5686.5 5691.9 5718.6 5721.6 5131.6 5133.2 5157.3 5186.9 5217.6 5202.7 5225.7 5227.7 5229.3 5232.9 5120.6 5183.8 5185.8 5194.2 5207.6 5144.8 5218.2 5218.2 5226.1 5279.7 5038.6 5085.8 5142.2 5157.7 5164.5 5593.1 5605.7 5669.3 5686.9 5744.9 6199.4 6235.1 6287.4 6309.7 6320.4 6293.6 6301.0 6307.5 6330.1 6337.9 5734.9 5793.4 5800.1 5817.0 5922.8 6254.3 6330.9 6340.4 6347.8 6378.8 6084.3 6095.9 6103.3 6125.4 6155.1 6121.8 6208.0 6240.6 6248.1 6301.9 6236.8 6255.7 6278.4 6288.2 6301.7 6260.9 6297.1 6310.0 6329.2 6329.2 4805.6 4904.4 4931.4 4938.0 4979.7 4429.2 4454.3 4458.2 4493.5 4510.4 5709.7 5741.1 5744.6 5749.0 5754.6 5183.2 6123.1 6265.0 6315.8 6337.8 5647.4 5668.4 5701.5 5704.5 5706.9 5344.8 5382.1 5398.1 5400.4 5401.2 6251.6 6261.5 6293.5 6318.0 6343.4 6261.6 6286.1 6307.4 6309.1 6312.7 6297.7 6304.5 6319.7 6328.0 6328.1 6074.3 6101.0 6146.6 6166.5 6179.4 6184.0 6206.8 6219.8 6229.8 6233.3 5914.7 5945.8 5948.2 5997.1 6006.7 5451.2 5616.3 6060.4 6075.1 6080.8 5901.3 5958.7 5961.9 5963.7 5966.4 6090.5 6092.9 6116.8 6117.4 6118.6 5836.4 5926.8 5927.7 5933.1 5939.2 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:55241.07 Median:34870.07 stddev:107718.12 (308.9%) stddev-sans-first:107941.90 16:37:27 INFO - PROCESS | 1661 | Values: 502.8 549.6 596.5 604.3 605.9 28097.0 28618.4 28642.9 36560.8 37057.3 40942.6 41407.0 41489.6 41530.0 41740.6 9351.7 9605.3 9608.5 9619.5 9625.1 8158.4 8357.1 9394.5 10967.0 11216.7 11077.5 11171.2 11185.0 11194.6 11197.0 8177.8 11117.4 11280.2 11289.8 11295.3 10871.6 11140.2 11151.7 11152.4 11154.6 35999.8 36595.3 36646.5 36657.3 36700.0 3186.1 3190.5 3266.9 3338.5 3522.6 26880.6 27448.7 27813.7 27993.2 28894.8 2643.5 2760.8 2774.6 2791.3 2879.0 365822.0 367215.0 368928.0 369377.0 370738.3 56986.0 57297.7 57693.0 57730.7 57885.1 60451.4 60661.1 60696.7 60857.8 60967.0 16069.0 16353.0 16363.1 16371.7 16390.2 11021.9 11234.3 12765.7 12819.2 12839.7 14967.4 15123.1 15140.5 15207.7 15371.3 5557.7 6660.3 6676.0 6748.0 6764.5 955.9 1008.6 1026.7 1091.3 1132.3 28173.7 28257.0 29014.4 29079.3 29281.7 1070.7 1152.7 1155.3 1163.4 1173.5 14494.8 14617.0 14924.8 14948.7 14984.3 478181.0 482622.0 483080.0 484914.0 485678.0 53865.2 54461.7 54500.0 54703.0 54747.5 1306.6 1400.6 1441.3 1494.3 1508.6 27689.7 28035.0 28618.0 29155.1 29428.4 45898.9 46302.0 46862.8 46915.1 47054.8 355935.0 357742.0 357807.0 358304.0 358331.2 29974.9 35222.3 35781.0 36122.3 36556.3 39873.6 41022.0 42950.8 42965.6 44211.6 34545.7 35846.0 36355.0 36485.6 36703.8 34667.0 35119.1 35161.0 35831.6 36044.9 34737.3 35002.8 35381.8 35557.1 35683.8 30271.7 31628.1 31635.7 31709.8 32180.0 21654.0 23242.5 23613.5 23795.5 24129.4 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:32119.51 Median:11287.46 stddev:87825.56 (778.1%) stddev-sans-first:88039.63 16:37:27 INFO - PROCESS | 1661 | Values: 397.0 401.4 407.1 407.8 418.0 13340.9 19236.0 19760.0 19762.1 19791.3 14204.4 14852.3 15151.8 19249.9 19941.0 5641.4 5878.2 5883.4 5886.0 5886.4 4876.6 4970.9 5758.7 5766.5 5837.8 4075.5 4314.6 5965.7 6307.3 6315.6 6242.3 6291.1 6300.5 6330.2 6351.2 4862.4 5413.8 5514.9 5628.7 5633.3 20097.6 21184.6 25246.9 25294.5 25640.2 2023.4 2087.7 2142.3 2224.8 2757.8 14282.4 14339.7 14418.9 15325.8 15347.8 1670.3 1771.9 1792.9 1812.9 1872.5 111613.0 119881.4 119917.0 120318.0 120839.0 9694.1 11476.9 11537.6 11645.5 11651.9 14912.0 14966.9 14968.6 14981.6 14998.0 7548.2 8559.2 8612.7 8623.5 8719.6 7676.2 8245.8 8449.0 8573.5 8580.7 4565.5 4644.0 4658.6 4664.2 4664.3 1780.4 1910.1 1970.8 1971.3 1998.7 752.6 823.5 947.5 982.6 990.0 19381.7 19906.0 20034.1 20159.8 20487.2 853.2 921.5 979.4 979.6 985.9 10550.1 10680.1 10731.6 10750.9 10752.5 480010.0 509857.8 536539.0 545905.0 546755.0 100249.0 111016.4 112448.4 112785.0 115916.0 1003.7 1022.7 1055.3 1056.5 1058.6 17054.3 17542.3 17805.4 17850.7 18038.2 49153.8 52038.7 52296.2 52355.7 52605.7 74304.0 74436.0 74768.0 75087.0 76888.6 7703.8 7827.4 7939.6 7990.3 8297.5 10160.1 10180.2 12089.8 12113.4 12311.7 10524.9 10648.5 10791.9 10852.7 10863.3 10288.4 10336.5 10439.9 10691.7 10712.0 10706.4 10757.9 10962.8 11056.4 11121.1 6501.4 7825.3 8069.5 8127.0 8229.9 10132.9 10632.4 10739.5 10953.8 10972.0 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5591.52 Median:5194.44 stddev:3090.54 (59.5%) stddev-sans-first:3083.27 16:37:27 INFO - PROCESS | 1661 | Values: 1416.0 1585.1 1588.0 1846.6 1983.3 7845.1 8507.1 8742.0 8742.4 8756.6 8354.5 8632.5 8643.6 8646.7 8676.0 2399.6 2399.9 2407.4 2436.8 2437.7 2136.2 2407.1 2438.7 2451.3 2456.7 2416.3 2438.7 2447.4 2460.0 2462.9 2399.2 2424.0 2434.9 2437.0 2438.8 2380.6 2407.5 2409.7 2412.3 2417.4 8462.6 8505.6 8670.0 8674.7 8676.4 5166.4 5383.8 5394.5 5394.9 5438.6 3468.3 3556.5 3623.9 3631.4 3638.8 1011.0 1049.6 1054.2 1061.7 1068.9 11337.6 11392.0 11549.7 11551.4 11555.1 4556.2 4559.6 4703.2 4704.0 4711.8 9061.1 9074.2 9092.5 9111.5 9116.3 5222.5 5288.8 5293.0 5296.4 5308.9 4425.9 4587.4 4782.1 4837.8 4952.3 3228.3 3699.3 3718.8 3812.0 3816.7 1181.4 1216.3 1219.5 1226.4 1227.8 661.8 785.2 862.1 902.4 936.1 5604.5 5723.4 5823.0 5939.2 6047.1 898.4 915.8 928.5 933.4 959.0 3390.3 3604.9 3806.8 3910.4 3980.1 10062.9 10607.4 10684.1 10690.3 10696.7 9881.3 10503.9 10625.4 10628.8 10657.0 6513.0 8107.9 8117.8 8198.5 8210.3 5784.8 5801.9 5894.0 6023.8 6137.0 9601.8 9673.0 9685.3 9855.1 10026.2 8562.5 8858.8 9613.4 9959.7 9967.2 7280.2 7296.8 7454.0 7461.1 7516.5 7992.4 8119.6 8184.2 8199.3 8200.2 6129.4 7271.0 7436.3 7465.2 7559.1 7131.9 7227.0 7294.8 7358.6 7384.2 6927.8 6935.4 7019.4 7027.8 7035.0 4741.3 4787.0 4821.7 4876.5 5014.8 4610.9 4632.4 4642.4 4696.8 4827.2 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4661.52 Median:5177.51 stddev:1479.75 (28.6%) stddev-sans-first:1447.55 16:37:27 INFO - PROCESS | 1661 | Values: 318.1 320.5 325.0 326.5 330.6 4467.7 5229.0 5328.2 5362.5 5400.6 4456.9 4696.5 5360.9 5374.9 5406.9 3898.0 4033.8 4041.0 4048.5 4070.0 3259.7 3771.2 4081.9 4134.7 4139.8 4001.0 4017.6 4042.0 4067.2 4091.7 3853.3 4062.8 4075.6 4079.5 4093.6 2933.6 3516.9 3527.5 3543.9 3559.1 4389.3 4458.9 5189.2 5245.9 5264.2 3850.4 4080.8 4111.1 4666.0 4687.5 4976.7 5637.7 5686.7 5688.4 5707.8 3327.1 3862.2 3878.9 3898.4 3909.9 5664.5 5941.8 5969.3 6000.9 6015.9 3782.8 3864.6 3923.8 3938.9 3945.6 3533.7 3853.2 3917.6 4000.3 4002.3 4076.7 4653.3 4679.5 4683.4 4701.7 4593.8 4958.6 5708.4 5726.4 5758.4 1247.7 1256.0 1389.4 1458.4 1467.0 900.4 915.4 950.6 971.7 978.3 4754.8 5360.9 5655.1 5678.6 5873.2 4913.4 6435.9 6502.4 6503.4 6527.9 5167.9 5537.2 5764.7 5804.8 5863.4 2872.9 3091.3 3300.9 3398.2 3426.2 5559.4 6224.1 6430.5 6711.5 6851.3 5346.0 6552.3 6636.4 6666.9 6688.4 5839.3 6023.0 6578.5 6686.3 6784.9 5159.7 5661.3 5828.8 6000.2 6001.5 4934.9 4937.4 5159.4 5169.5 5406.5 5945.9 6104.6 6159.3 6208.7 6245.9 3940.3 3940.7 3945.6 3954.6 4050.4 4973.4 5246.0 5886.7 5910.9 5953.9 5002.0 5406.9 5479.3 5779.7 5798.3 5241.6 5726.0 5753.1 5773.7 5775.5 5084.9 5185.5 5201.1 5493.6 5517.9 5123.9 5325.5 5350.5 5653.5 5838.5 4979.3 5202.5 5644.6 5686.5 5716.5 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:18212.71 Median:3302.45 stddev:25742.53 (779.5%) stddev-sans-first:25780.81 16:37:27 INFO - PROCESS | 1661 | Values: 608.6 725.5 738.4 741.7 748.8 12497.6 14711.3 15163.0 15211.2 15224.8 13772.6 13817.7 13898.3 13922.2 13933.5 723.1 745.1 870.7 910.6 928.1 909.3 910.7 913.9 914.7 915.4 963.0 964.4 964.5 966.4 966.6 752.3 780.4 780.9 781.1 958.7 698.1 723.1 725.7 726.3 726.3 12153.8 12736.2 14367.2 14827.8 14840.4 12177.4 12449.4 12656.2 12673.0 12673.4 9637.9 9973.9 9974.6 10017.5 10020.0 4007.3 4014.4 4019.5 4172.4 4344.2 74972.0 77828.6 77847.7 77853.6 77882.0 9380.4 9446.8 9855.2 9936.8 9959.9 21078.9 21122.7 21156.8 21164.9 21180.7 7525.8 7690.0 7904.5 7967.1 7978.3 4124.8 4229.0 4240.7 4290.2 4296.0 3448.2 3514.9 3520.0 3524.7 3525.9 639.9 643.9 658.4 660.4 662.0 534.5 540.6 552.5 573.2 598.0 8149.7 8155.9 8261.2 8594.5 8602.4 509.7 579.6 580.3 601.7 628.3 3800.8 3862.9 3900.4 3932.3 4129.4 77464.2 91982.1 97088.5 97458.0 100732.4 66782.3 80475.6 83766.0 83927.2 85125.0 75150.6 75345.6 75992.2 76271.0 76332.5 6006.9 7739.5 7954.8 8012.8 8101.5 45197.1 46188.5 46309.5 46355.0 46843.3 62699.0 63706.0 63719.0 64880.0 66870.0 11126.0 11201.3 11204.0 11276.8 11372.5 11429.2 12017.8 12018.5 12097.5 12268.1 11734.6 11736.2 11773.9 11954.7 11984.1 11565.4 11570.3 11690.5 11853.5 11898.6 10645.9 10747.3 11095.3 11248.9 11752.1 8354.9 9192.6 9775.7 9898.2 10038.7 9605.3 10028.2 10052.1 10055.7 10076.5 16:37:27 INFO - PROCESS | 1661 | -------- Summary: end -------- 16:37:27 INFO - PROCESS | 1661 | 16:37:27 INFO - TEST-INFO | 1661: exit 0 16:37:27 INFO - TEST-OK | dromaeo_css | took 1116285ms 16:37:27 INFO - TEST-START | kraken 16:37:27 INFO - operating with platform_type : mac_ 16:37:27 INFO - Initialising browser for kraken test... 16:37:27 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmp2CZKu5/profile http://localhost:49270/getInfo.html) 16:37:29 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 16:37:29 INFO - PROCESS | 1669 | colorDepth:24 16:37:29 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 16:37:29 INFO - PROCESS | 1669 | __metrics 16:37:29 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:37:30 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:37:30 INFO - TEST-INFO | 1669: exit 0 16:37:30 INFO - Browser initialized. 16:37:30 INFO - Running cycle 1/1 for kraken test... 16:37:30 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/T/tmp2CZKu5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:42 INFO - PROCESS | 1671 | RSS: Main: 239058944 16:37:42 INFO - PROCESS | 1671 | 16:37:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:46 INFO - PROCESS | 1671 | RSS: Main: 286425088 16:37:46 INFO - PROCESS | 1671 | 16:37:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 16:37:49 INFO - PROCESS | 1671 | RSS: Main: 299028480 16:37:49 INFO - PROCESS | 1671 | 16:37:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 16:37:53 INFO - PROCESS | 1671 | RSS: Main: 386543616 16:37:53 INFO - PROCESS | 1671 | 16:37:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 16:37:56 INFO - PROCESS | 1671 | RSS: Main: 287035392 16:37:56 INFO - PROCESS | 1671 | 16:37:58 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:59 INFO - PROCESS | 1671 | RSS: Main: 291680256 16:37:59 INFO - PROCESS | 1671 | 16:38:01 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 16:38:02 INFO - PROCESS | 1671 | RSS: Main: 328081408 16:38:02 INFO - PROCESS | 1671 | 16:38:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:05 INFO - PROCESS | 1671 | RSS: Main: 330276864 16:38:05 INFO - PROCESS | 1671 | 16:38:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 16:38:09 INFO - PROCESS | 1671 | RSS: Main: 329625600 16:38:09 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:11 INFO - PROCESS | 1671 | RSS: Main: 321060864 16:38:11 INFO - PROCESS | 1671 | 16:38:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:13 INFO - PROCESS | 1671 | RSS: Main: 327806976 16:38:13 INFO - PROCESS | 1671 | 16:38:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:17 INFO - PROCESS | 1671 | RSS: Main: 330096640 16:38:17 INFO - PROCESS | 1671 | 16:38:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:20 INFO - PROCESS | 1671 | RSS: Main: 331100160 16:38:20 INFO - PROCESS | 1671 | 16:38:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:24 INFO - PROCESS | 1671 | RSS: Main: 326754304 16:38:24 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 16:38:26 INFO - PROCESS | 1671 | RSS: Main: 391090176 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | __start_tp_report 16:38:26 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:38:26 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:38:26 INFO - PROCESS | 1671 | |i|pagename|runs| 16:38:26 INFO - PROCESS | 1671 | |0;ai-astar;114;113;109;113;113;113;113;113;103;113 16:38:26 INFO - PROCESS | 1671 | |1;audio-beat-detection;157;118;146;199;130;149;165;120;173;122 16:38:26 INFO - PROCESS | 1671 | |2;audio-dft;186;188;185;184;185;180;183;185;182;183 16:38:26 INFO - PROCESS | 1671 | |3;audio-fft;75;71;88;90;56;60;59;82;85;54 16:38:26 INFO - PROCESS | 1671 | |4;audio-oscillator;87;97;140;109;119;124;122;80;77;124 16:38:26 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;89;86;89;85;88;85;89;91;130;84 16:38:26 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;93;93;92;92;93;94;93;93 16:38:26 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;84;86;87;86;83;96;84;85;87 16:38:26 INFO - PROCESS | 1671 | |8;json-parse-financial;62;60;60;59;59;58;60;79;60;58 16:38:26 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;66;69;79;72;69;64;65;67;66 16:38:26 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;98;105;106;108;68;94;107;106;104;84 16:38:26 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;140;142;152;158;151;140;139;148;196;158 16:38:26 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;199;200;226;267;203;199;207;197;193;197 16:38:26 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;70;73;70;68;69;70;74;76;96;80 16:38:26 INFO - PROCESS | 1671 | __end_tp_report 16:38:26 INFO - PROCESS | 1671 | __start_cc_report 16:38:26 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2001 16:38:26 INFO - PROCESS | 1671 | __end_cc_report 16:38:26 INFO - PROCESS | 1671 | __startTimestamp1461713906623__endTimestamp 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:38:26 INFO - PROCESS | 1671 | Number of tests: 14 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.33 (3.0%) stddev-sans-first:3.43 16:38:26 INFO - PROCESS | 1671 | Values: 114.0 113.0 109.0 113.0 113.0 113.0 113.0 113.0 103.0 113.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:153.00 stddev:26.43 (17.3%) stddev-sans-first:27.82 16:38:26 INFO - PROCESS | 1671 | Values: 157.0 118.0 146.0 199.0 130.0 149.0 165.0 120.0 173.0 122.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:2.23 (1.2%) stddev-sans-first:2.26 16:38:26 INFO - PROCESS | 1671 | Values: 186.0 188.0 185.0 184.0 185.0 180.0 183.0 185.0 182.0 183.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:72.00 Median:78.50 stddev:13.95 (17.8%) stddev-sans-first:14.76 16:38:26 INFO - PROCESS | 1671 | Values: 75.0 71.0 88.0 90.0 56.0 60.0 59.0 82.0 85.0 54.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:107.90 Median:108.50 stddev:21.50 (19.8%) stddev-sans-first:21.43 16:38:26 INFO - PROCESS | 1671 | Values: 87.0 97.0 140.0 109.0 119.0 124.0 122.0 80.0 77.0 124.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:88.50 stddev:13.68 (15.5%) stddev-sans-first:14.48 16:38:26 INFO - PROCESS | 1671 | Values: 89.0 86.0 89.0 85.0 88.0 85.0 89.0 91.0 130.0 84.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:38:26 INFO - PROCESS | 1671 | Values: 93.0 93.0 93.0 93.0 92.0 92.0 93.0 94.0 93.0 93.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:86.00 stddev:3.71 (4.3%) stddev-sans-first:3.84 16:38:26 INFO - PROCESS | 1671 | Values: 84.0 84.0 86.0 87.0 86.0 83.0 96.0 84.0 85.0 87.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:60.00 stddev:6.26 (10.4%) stddev-sans-first:6.64 16:38:26 INFO - PROCESS | 1671 | Values: 62.0 60.0 60.0 59.0 59.0 58.0 60.0 79.0 60.0 58.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:4.58 (6.7%) stddev-sans-first:4.61 16:38:26 INFO - PROCESS | 1671 | Values: 64.0 66.0 69.0 79.0 72.0 69.0 64.0 65.0 67.0 66.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:98.00 Median:88.00 stddev:12.94 (14.7%) stddev-sans-first:13.72 16:38:26 INFO - PROCESS | 1671 | Values: 98.0 105.0 106.0 108.0 68.0 94.0 107.0 106.0 104.0 84.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:151.50 stddev:16.93 (11.2%) stddev-sans-first:17.35 16:38:26 INFO - PROCESS | 1671 | Values: 140.0 142.0 152.0 158.0 151.0 140.0 139.0 148.0 196.0 158.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.80 Median:201.50 stddev:22.40 (11.1%) stddev-sans-first:23.48 16:38:26 INFO - PROCESS | 1671 | Values: 199.0 200.0 226.0 267.0 203.0 199.0 207.0 197.0 193.0 197.0 16:38:26 INFO - PROCESS | 1671 | 16:38:26 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:73.50 stddev:8.37 (11.4%) stddev-sans-first:8.71 16:38:26 INFO - PROCESS | 1671 | Values: 70.0 73.0 70.0 68.0 69.0 70.0 74.0 76.0 96.0 80.0 16:38:26 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:38:26 INFO - PROCESS | 1671 | 16:38:27 INFO - TEST-INFO | 1671: exit 0 16:38:27 INFO - TEST-OK | kraken | took 59687ms 16:38:27 INFO - SUITE-END | took 1175s 16:38:27 INFO - Completed test suite (00:19:36) 16:38:27 INFO - javascript benchmark 16:38:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2771.3764403009327, 3437.192259818942, 3454.2400671852215, 3454.412749832529, 3508.403571392863, 5044.72254026028, 5252.579793616511, 5587.469190426903, 5667.178259152425, 5691.7723291068305, 5635.718214089289, 5686.488216060554, 5691.8577035574135, 5718.571107166958, 5721.5708821838425, 5131.589472842164, 5133.178691409385, 5157.252198431225, 5186.948130518703, 5217.58259339252, 5202.687838729669, 5225.660332078422, 5227.73861306935, 5229.294045303893, 5232.8691782705355, 5120.564751996085, 5183.792648294083, 5185.844424667266, 5194.246834209038, 5207.557357624588, 5144.845654630367, 5218.191180367049, 5218.191180367049, 5226.111561034595, 5279.736013199363, 5038.647294689373, 5085.821996230115, 5142.202958541432, 5157.71632560209, 5164.483551644806, 5593.105103183471, 5605.691686957216, 5669.348024977139, 5686.914696279559, 5744.8851022979725, 6199.393721830024, 6235.095911092866, 6287.434130928193, 6309.652969086698, 6320.399562041599, 6293.559450838443, 6300.968495157541, 6307.5269354798575, 6330.082138089997, 6337.904931426032, 5734.942650573483, 5793.391693872083, 5800.0719884818645, 5817.0, 5922.822315330547, 6254.34329395416, 6330.873382532323, 6340.397662222082, 6347.841303967345, 6378.77674281398, 6084.30030546484, 6095.908561371583, 6103.298120716175, 6125.356837532084, 6155.076738489173, 6121.8493044937295, 6207.96896015517, 6240.625562466266, 6248.094026366151, 6301.936980630227, 6236.812895613139, 6255.6872156392, 6278.372162783685, 6288.245410550671, 6301.6534090625455, 6260.937390626126, 6297.118403423525, 6310.0, 6329.240491141092, 6329.240491141092, 4805.639577031667, 4904.362432883774, 4931.408231012229, 4938.037082768826, 4979.726115063706, 4429.180601588717, 4454.331850222427, 4458.197524445631, 4493.460784705856, 4510.391097201901, 5709.714514274269, 5741.1388291756575, 5744.569157313218, 5749.0, 5754.625949313365, 5183.222516622537, 6123.099607468126, 6265.0, 6315.842103947438, 6337.7781777637565, 5647.350554686182, 5668.433156684298, 5701.486866182064, 5704.543636509069, 5706.942930570641, 5344.759485823147, 5382.058139825514, 5398.13629819227, 5400.378956419983, 5401.216823560561, 6251.624902505864, 6261.530385221035, 6293.527985401113, 6317.96841015792, 6343.365663433619, 6261.592996455216, 6286.119943207863, 6307.432331090224, 6309.1167236587835, 6312.684365781601, 6297.748090076529, 6304.464120549805, 6319.652419116809, 6327.96836015817, 6328.145700330397, 6074.271087469532, 6101.03536932808, 6146.6170111724505, 6166.537509686881, 6179.3820617937845, 6184.041473571604, 6206.813795586139, 6219.782307619212, 6229.844253893689, 6233.345498722751, 5914.733836977261, 5945.821625351246, 5948.196993406008, 5997.070454079625, 6006.729697163725, 5451.182322651475, 5616.297962754656, 6060.424259695322, 6075.149479072845, 6080.7567697291615, 5901.29184497863, 5958.731857066529, 5961.850953726192, 5963.70181490915, 5966.373530779206, 6090.484362135808, 6092.876921702668, 6116.785912493041, 6117.418845209698, 6118.60229085108, 5836.445537673914, 5926.822195334147, 5927.703614819328, 5933.1396947443245, 5939.227900372924], "value": 5683.107932329371, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.76118843549295, 549.648225135913, 596.4572239262272, 604.2658170323062, 605.9244840408275, 28097.0, 28618.427631447386, 28642.856785716067, 36560.81719591398, 37057.25885482289, 40942.59057409425, 41407.0, 41489.58510414895, 41529.96175095624, 41740.58259417405, 9351.719448416541, 9605.327627066108, 9608.471534065624, 9619.519024048805, 9625.085616866421, 8158.388120890927, 8357.080721120672, 9394.530273486333, 10966.958138976785, 11216.66350009501, 11077.50151243196, 11171.218014738972, 11185.0, 11194.608188713397, 11197.048250898684, 8177.754667359989, 11117.444127793618, 11280.210385273034, 11289.774204515905, 11295.322280663186, 10871.565137394495, 11140.220184587, 11151.665450036511, 11152.442377881154, 11154.553817847276, 35999.82000090009, 36595.268094637955, 36646.450303245474, 36657.26685466302, 36700.0, 3186.1078897908624, 3190.4735718606403, 3266.9183270418193, 3338.5159151923076, 3522.6125126236093, 26880.59679104815, 27448.725512744815, 27813.721862781316, 27993.160205193875, 28894.844206231726, 2643.47130573886, 2760.7791376689815, 2774.556071028646, 2791.316127548743, 2878.9856050719613, 365822.0, 367215.0, 368928.0, 369377.0, 370738.29261707305, 56986.0, 57297.71351143301, 57693.0, 57730.711346443, 57885.13172302419, 60451.395486045454, 60661.09008364878, 60696.696516518015, 60857.782844343754, 60967.0, 16069.035857848567, 16353.018818870905, 16363.100029498491, 16371.672566548603, 16390.180490975403, 11021.944890275658, 11234.3259404436, 12765.744685106245, 12819.230846149261, 12839.743205135845, 14967.401303947938, 15123.092614442949, 15140.545783626509, 15207.695846083016, 15371.308291126927, 5557.694326812064, 6660.333966603398, 6675.966620166965, 6748.021536877125, 6764.526483146133, 955.9426434413957, 1008.594502112467, 1026.6817286641165, 1091.307020042279, 1132.3404339484478, 28173.732182051855, 28257.0, 29014.41971160565, 29079.27301817429, 29281.707182928323, 1070.7323169207698, 1152.7175841918784, 1155.3472288157163, 1163.3834067944003, 1173.4602083041725, 14494.782578261334, 14616.976811623297, 14924.776128358084, 14948.701025979419, 14984.250787460584, 478181.0, 482622.0, 483080.0, 484914.0, 485678.0, 53865.1920221197, 54461.72769136208, 54500.0, 54703.0, 54747.45252547503, 1306.5884246462333, 1400.5658245943587, 1441.2505497141638, 1494.3126161965617, 1508.630385555546, 27689.723102768716, 28035.0, 28618.0, 29155.125346239645, 29428.411431771674, 45898.85252868705, 46302.0, 46862.76568617135, 46915.06169876515, 47054.76472617615, 355935.0, 357742.0, 357807.0, 358304.0, 358331.2083439566, 29974.850125750105, 35222.29555408929, 35781.0, 36122.27755444929, 36556.26887462183, 39873.60126398699, 41022.0, 42950.78524607357, 42965.57034429616, 44211.55788442203, 34545.654543455246, 35846.0, 36355.0, 36485.63514364822, 36703.760360738204, 34667.0, 35119.12202194965, 35161.0, 35831.64168358283, 36044.91865244047, 34737.30525389427, 35002.824985874904, 35381.82309088438, 35557.1110722234, 35683.82158089193, 30271.697283026886, 31628.05115846528, 31635.683643163273, 31709.8414507926, 32180.0, 21654.025568849756, 23242.535149296582, 23613.52772944497, 23795.524089518458, 24129.39676508031], "value": 18921.353330799087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.0, 401.38187191724745, 407.0698454032538, 407.7594219410547, 417.99164016719664, 13340.866591334083, 19236.0, 19760.01199940001, 19762.110705018273, 19791.307304244354, 14204.431822727105, 14852.257387130654, 15151.848481515182, 19249.903750481262, 19941.0, 5641.379448260698, 5878.235829342179, 5883.441073098049, 5885.969955257834, 5886.381929897363, 4876.609871210295, 4970.925436118461, 5758.654480731169, 5766.509846663039, 5837.824865254028, 4075.470188875442, 4314.590113939171, 5965.701714914237, 6307.337729538402, 6315.5894866833505, 6242.313345531988, 6291.087792270118, 6300.527460440462, 6330.240371155444, 6351.237851457809, 4862.416510018785, 5413.783448662089, 5514.889702205974, 5628.690422026787, 5633.295838020247, 20097.58828940522, 21184.576308473745, 25246.873765631237, 25294.494110117696, 25640.230793076236, 2023.4132101690639, 2087.749470063587, 2142.271627646592, 2224.788645078715, 2757.820741651806, 14282.3572939218, 14339.71320573593, 14418.927905360511, 15325.770113448307, 15347.846521534864, 1670.2901266961615, 1771.8759686821927, 1792.9193186306647, 1812.909354532268, 1872.4944265048498, 111613.0, 119881.40059299648, 119917.0, 120318.0, 120839.0, 9694.127528522326, 11476.885231147582, 11537.596184133516, 11645.53417863293, 11651.883481165249, 14912.0, 14966.850331496764, 14968.625784355261, 14981.550553483195, 14998.0, 7548.207438219018, 8559.186877246651, 8612.741617751477, 8623.5257060861, 8719.564021798884, 7676.193999629959, 8245.752627421187, 8449.0, 8573.485590864568, 8580.699675511327, 4565.543445655408, 4643.953560464352, 4658.580727734452, 4664.183767840615, 4664.346991421204, 1780.4213630570116, 1910.1117980139024, 1970.7930667280016, 1971.2706298669584, 1998.6702194138095, 752.6424948149585, 823.4853216739538, 947.5309721687809, 982.6167794560128, 989.9950500247453, 19381.709274360896, 19906.0, 20034.098465568793, 20159.798402015793, 20487.18051277962, 853.185208126238, 921.5300196899495, 979.4074584876047, 979.5983646704817, 985.9014098590227, 10550.103241224582, 10680.145588352912, 10731.624393146203, 10750.892491075145, 10752.462376881125, 480010.0, 509857.76066795137, 536539.0, 545905.0, 546755.0, 100249.0, 111016.4449177765, 112448.4377578107, 112785.0, 115916.0, 1003.7440452684518, 1022.7136401807505, 1055.2507719519228, 1056.529844219315, 1058.5871510111062, 17054.31782728677, 17542.298308067533, 17805.376811811526, 17850.732239016164, 18038.188281527626, 49153.75423122862, 52038.69903252449, 52296.21555676592, 52355.73822130865, 52605.7369713149, 74304.0, 74436.0, 74768.0, 75087.0, 76888.6155569241, 7703.768886933401, 7827.373810095065, 7939.6030198491, 7990.320822730133, 8297.543635100128, 10160.13968570716, 10180.185585153318, 12089.75820483603, 12113.394330283274, 12311.692207694521, 10524.947375263075, 10648.467576621293, 10791.892081079088, 10852.728681783019, 10863.293885897392, 10288.434136122585, 10336.483175841327, 10439.90381009983, 10691.679249622524, 10712.035916767529, 10706.357618542912, 10757.946210269212, 10962.835557466486, 11056.391898445663, 11121.110311174925, 6501.382368674968, 7825.295723384923, 8069.515829050276, 8126.967705996879, 8229.917700822914, 10132.949335253277, 10632.362058276529, 10739.46302684847, 10953.83569246446, 10972.0], "value": 9343.98445326665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.9929200353997, 1585.0964949978509, 1588.0074953154278, 1846.6214426042663, 1983.3157560641582, 7845.137034926153, 8507.064222935473, 8741.950965884103, 8742.388032837704, 8756.562171891412, 8354.456960297577, 8632.482051076924, 8643.611037503311, 8646.697365592205, 8676.045634980148, 2399.6160614301716, 2399.8800059997016, 2407.3981504623844, 2436.8294219404647, 2437.731849496554, 2136.2202795979433, 2407.145463360508, 2438.743931887153, 2451.2891261534132, 2456.680631517909, 2416.2871952773976, 2438.6951631046118, 2447.4248551590363, 2459.950800983979, 2462.91379801707, 2399.244238065004, 2423.987880060597, 2434.914777982762, 2436.9756302436926, 2438.7561243875557, 2380.571497130516, 2407.4944261705064, 2409.6746939163163, 2412.3124909400913, 2417.4439878827966, 8462.576871156418, 8505.574721263913, 8670.046294907555, 8674.653013879437, 8676.436031657986, 5166.380034395859, 5383.838484845461, 5394.541463975567, 5394.91907621386, 5438.619296649236, 3468.271662950783, 3556.484309775094, 3623.9094022649383, 3631.4189729643394, 3638.818059097061, 1011.044562888071, 1049.648367796789, 1054.2356791326304, 1061.692109288313, 1068.8984546468073, 11337.603183888525, 11391.974722274952, 11549.6535103947, 11551.422428878523, 11555.133364997659, 4556.225441674923, 4559.566841150086, 4703.176944034849, 4704.0, 4711.811527538928, 9061.13919177677, 9074.228690561376, 9092.499912504876, 9111.498867562348, 9116.316276279305, 5222.503862133091, 5288.8413347599635, 5293.0, 5296.390915044819, 5308.867278318073, 4425.933610995838, 4587.403637527167, 4782.139214941344, 4837.758112094381, 4952.306677065214, 3228.257500774835, 3699.3341198583976, 3718.8326525306425, 3812.0, 3816.713746469026, 1181.3561608922985, 1216.2945491614842, 1219.4939100273464, 1226.3868065967017, 1227.8403807505147, 661.8279247395711, 785.1520358013332, 862.1034124510564, 902.3638334973828, 936.1318121191397, 5604.523615492647, 5723.370429252779, 5823.0, 5939.227900372924, 6047.092936059538, 898.4160295807777, 915.789368445248, 928.5450129436528, 933.3979583168818, 959.0, 3390.288039511681, 3604.9005053458945, 3806.8286927088348, 3910.432987216838, 3980.144268982183, 10062.899371006342, 10607.363558186533, 10684.09185219265, 10690.30513016667, 10696.679099627023, 9881.25890558211, 10503.949605039435, 10625.362478251329, 10628.840567391495, 10656.9875861793, 6512.967435162888, 8107.878381824277, 8117.756467305991, 8198.549079800548, 8210.30212431938, 5784.768609255582, 5801.883962320814, 5893.9685555026845, 6023.789167379121, 6136.969315153396, 9601.759956001157, 9673.0, 9685.273604479551, 9855.11303982645, 10026.248031397528, 8562.48625082497, 8858.822823543622, 9613.423194608346, 9959.651412200828, 9967.152792012761, 7280.1991780903, 7296.817579560554, 7454.030975973089, 7461.104667439942, 7516.5114267572435, 7992.440529162903, 8119.594020298843, 8184.2224988625985, 8199.344052475668, 8200.179982001751, 6129.417705317987, 7271.0, 7436.309842934008, 7465.1788303287685, 7559.055118110236, 7131.893021604784, 7227.0, 7294.817629559304, 7358.632068396666, 7384.150822655463, 6927.792166235021, 6935.410490108397, 7019.403350715246, 7027.789166325018, 7034.964825176046, 4741.2651039089, 4786.95213047865, 4821.662483626113, 4876.512348765237, 5014.84955451337, 4610.907781844277, 4632.444106707217, 4642.419697537808, 4696.765161741968, 4827.2035114207165], "value": 4514.978423931829, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.1156385249007, 320.52242159182816, 325.04436955351264, 326.46948708348935, 330.57686161712985, 4467.709598876071, 5228.973855130719, 5328.200769884515, 5362.4637536246355, 5400.621956463049, 4456.910861782762, 4696.530346965302, 5360.9463905360935, 5374.865628359293, 5406.9456455991, 3897.9670387347446, 4033.818478168475, 4041.0, 4048.4736984192014, 4070.0, 3259.722923551501, 3771.18919432322, 4081.9183616327655, 4134.710570260083, 4139.799458157131, 4000.9799951000205, 4017.598240175974, 4041.979790101045, 4067.1662309226685, 4091.7340372875815, 3853.344931361675, 4062.7968601569805, 4075.551689314188, 4079.51045874494, 4093.6111069448357, 2933.559966005095, 3516.894493165209, 3527.4532447470683, 3543.858245670196, 3559.14580500678, 4389.253826849444, 4458.929856834369, 5189.195674670432, 5245.895082098336, 5264.184051472028, 3850.441685955521, 4080.755154690728, 4111.054457474797, 4665.953340466586, 4687.484376718558, 4976.701397916137, 5637.689927054011, 5686.715664216814, 5688.402717714663, 5707.800226992035, 3327.0536736009462, 3862.2275544890854, 3878.8836334909997, 3898.3567711327596, 3909.882703518899, 5664.49019588239, 5941.821745347646, 5969.283685957669, 6000.879982400327, 6015.909761353583, 3782.7730336179916, 3864.555576108727, 3923.7645741255615, 3938.877419935362, 3945.6054394560315, 3533.717302615785, 3853.2293541291856, 3917.6484112980975, 4000.299947509174, 4002.298448547219, 4076.7146299758733, 4653.324803070892, 4679.4618618858585, 4683.370387949367, 4701.717896926127, 4593.770311484479, 4958.603311735053, 5708.431205957253, 5726.398728133569, 5758.395368486333, 1247.7255003899127, 1255.9763792509075, 1389.3678376338944, 1458.3801884199386, 1466.955991320262, 900.4192295969063, 915.4461550761694, 950.6435086842434, 971.7036303927229, 978.2516374973151, 4754.762261886892, 5360.892782144413, 5655.115890895673, 5678.5803549112725, 5873.177755114202, 4913.410390753133, 6435.903461448082, 6502.414782669583, 6503.382178693017, 6527.89025865604, 5167.870803229949, 5537.169424586345, 5764.682942438206, 5804.82585522435, 5863.355030946593, 2872.9281767955968, 3091.335362896988, 3300.9436980166597, 3398.218409765718, 3426.2119712466274, 5559.388467268597, 6224.087117238641, 6430.549861509743, 6711.530192886548, 6851.2806155353965, 5346.0, 6552.312007239267, 6636.407262257024, 6666.899996500057, 6688.39804417595, 5839.270091238595, 6023.006203976295, 6578.473722102316, 6686.331366863372, 6784.932150678529, 5159.664621799571, 5661.320641523043, 5828.7959921404245, 6000.159977603226, 6001.459868611846, 4934.925976110289, 4937.382827146606, 5159.355080614923, 5169.4830516948, 5406.513413792777, 5945.910811337747, 6104.572679912363, 6159.322474527888, 6208.720607572761, 6245.875082498415, 3940.271049855786, 3940.684745220433, 3945.605439456146, 3954.62431069048, 4050.4126901599693, 4973.35346404965, 5246.029484545371, 5886.6754980130845, 5910.876933382644, 5953.851153721192, 5002.049610573979, 5406.8648283791645, 5479.260299859628, 5779.7110144493445, 5798.304203495608, 5241.580673546031, 5726.024521540062, 5753.1370294456165, 5773.653580785166, 5775.48020678141, 5084.923516397869, 5185.533302002837, 5201.115810312181, 5493.642913210629, 5517.862053448696, 5123.897522049464, 5325.547328477122, 5350.491703288181, 5653.491185793298, 5838.474537291664, 4979.311171967097, 5202.505761952609, 5644.604877658525, 5686.516646085129, 5716.456936591017], "value": 4202.215632260498, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.6013661052011, 725.4812808841681, 738.3760722189747, 741.6773703439003, 748.7566540874213, 12497.625071247865, 14711.337989790458, 15163.0, 15211.239438028097, 15224.771628425582, 13772.586822395344, 13817.654558636039, 13898.305084745773, 13922.1646701198, 13933.512327068554, 723.1322413104269, 745.083547236899, 870.6517393042802, 910.5583791860959, 928.0997432490474, 909.3225546967518, 910.6721580231111, 913.9314551408638, 914.6615752171706, 915.3638221436105, 963.02734238419, 964.3731574476611, 964.4502633498909, 966.3815158298695, 966.5891995901713, 752.2702978111223, 780.4302858913026, 780.878963760618, 781.0939310399909, 958.6740508227201, 698.078536332042, 723.0889079759488, 725.6843273176186, 726.259215600085, 726.2737262737263, 12153.756924861453, 12736.172148810298, 14367.209803460806, 14827.77758333626, 14840.406383744637, 12177.391130443442, 12449.377531123408, 12656.240625562403, 12673.0, 12673.429695663717, 9637.855432168522, 9973.90026099737, 9974.551145198484, 10017.549210285555, 10020.0, 4007.2586571484076, 4014.3978403239457, 4019.537753158366, 4172.395002724618, 4344.218040752663, 74972.0, 77828.61085694535, 77847.66457006242, 77853.61073194654, 77882.0, 9380.390274632127, 9446.811063778687, 9855.211583073336, 9936.80126397468, 9959.900400995897, 21078.894605526875, 21122.683159752614, 21156.78843211579, 21164.894175529025, 21180.682289765668, 7525.8118547035665, 7689.9615501922135, 7904.525728456311, 7967.123616402191, 7978.3218426434405, 4124.773137477468, 4228.9788551057045, 4240.703150779415, 4290.227759003347, 4295.978520107379, 3448.189675426273, 3514.9121271968406, 3520.0143959691327, 3524.665156810099, 3525.9294814104087, 639.880209633142, 643.8551325951623, 658.3745441830281, 660.3932612123136, 661.9635920024444, 534.5349545895018, 540.6134613751187, 552.4613501835759, 573.2347316332659, 597.9581429299994, 8149.6630303990605, 8155.918440815634, 8261.17388261181, 8594.527300998505, 8602.35482338827, 509.706918521844, 579.5827004556712, 580.3007376111775, 601.6841158391792, 628.2963081348919, 3800.8289626966853, 3862.864799731996, 3900.4344370066183, 3932.2528719543197, 4129.380592911028, 77464.22535774682, 91982.08017919735, 97088.51455742677, 97458.0, 100732.43774279121, 66782.34142230394, 80475.59762201269, 83766.0, 83927.16072839315, 85125.0, 75150.62424687842, 75345.62327188438, 75992.24007759852, 76271.0, 76332.45844269467, 6006.939930600638, 7739.496932699357, 7954.761357159295, 8012.799680007814, 8101.5002962748285, 45197.12437383119, 46188.53811461842, 46309.53690463052, 46354.98692584971, 46843.29735053909, 62699.0, 63706.0, 63719.0, 64880.026799598985, 66870.0, 11126.0, 11201.271917325348, 11204.0, 11276.7744645105, 11372.54509819598, 11429.199956003, 12017.759644807229, 12018.519259229533, 12097.455614497192, 12268.141230113806, 11734.647960561197, 11736.178467507192, 11773.941130294294, 11954.701132471759, 11984.099088618317, 11565.363904985306, 11570.329795506292, 11690.4739286734, 11853.525858965544, 11898.643040708792, 10645.94677026641, 10747.301425407324, 11095.278806877526, 11248.887511124783, 11752.11859110553, 8354.916450835413, 9192.632294708137, 9775.657851975147, 9898.20814334837, 10038.749031274276, 9605.327627066039, 10028.162846935471, 10052.145567626834, 10055.721649996442, 10076.510903426792], "value": 6875.6305781982, "unit": "score"}], "name": "dromaeo_css", "value": 7127.325866734304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 109.0, 113.0, 113.0, 113.0, 113.0, 113.0, 103.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 118.0, 146.0, 199.0, 130.0, 149.0, 165.0, 120.0, 173.0, 122.0], "value": 147.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 185.0, 184.0, 185.0, 180.0, 183.0, 185.0, 182.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 71.0, 88.0, 90.0, 56.0, 60.0, 59.0, 82.0, 85.0, 54.0], "value": 72.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 97.0, 140.0, 109.0, 119.0, 124.0, 122.0, 80.0, 77.0, 124.0], "value": 107.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 85.0, 88.0, 85.0, 89.0, 91.0, 130.0, 84.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 92.0, 92.0, 93.0, 94.0, 93.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 87.0, 86.0, 83.0, 96.0, 84.0, 85.0, 87.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 59.0, 59.0, 58.0, 60.0, 79.0, 60.0, 58.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 69.0, 79.0, 72.0, 69.0, 64.0, 65.0, 67.0, 66.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 105.0, 106.0, 108.0, 68.0, 94.0, 107.0, 106.0, 104.0, 84.0], "value": 98.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 152.0, 158.0, 151.0, 140.0, 139.0, 148.0, 196.0, 158.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 200.0, 226.0, 267.0, 203.0, 199.0, 207.0, 197.0, 193.0, 197.0], "value": 208.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 70.0, 68.0, 69.0, 70.0, 74.0, 76.0, 96.0, 80.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1557.7, "alertThreshold": 2.0}]} 16:38:27 INFO - Return code: 0 16:38:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:38:27 ERROR - # TBPL SUCCESS # 16:38:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:38:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:38:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:38:27 INFO - 2016-04-26 16:38:27.908 screenresolution[1675:9958] starting screenresolution argv=screenresolution get 16:38:27 INFO - 2016-04-26 16:38:27.911 screenresolution[1675:9958] Display 0: 1600x1200x32@60 16:38:27 INFO - 2016-04-26 16:38:27.916 screenresolution[1676:9961] starting screenresolution argv=screenresolution list 16:38:27 INFO - Available Modes on Display 0 16:38:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:38:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:38:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:38:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:38:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:38:28 INFO - Intel Iris: 16:38:28 INFO - Chipset Model: Intel Iris 16:38:28 INFO - Type: GPU 16:38:28 INFO - Bus: Built-In 16:38:28 INFO - VRAM (Dynamic, Max): 1536 MB 16:38:28 INFO - Vendor: Intel (0x8086) 16:38:28 INFO - Device ID: 0x0a2e 16:38:28 INFO - Revision ID: 0x0009 16:38:28 INFO - Displays: 16:38:28 INFO - Display: 16:38:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:38:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:38:28 INFO - Display Serial Number: 1600x1200 60 16:38:28 INFO - Main Display: Yes 16:38:28 INFO - Mirror: Off 16:38:28 INFO - Online: Yes 16:38:28 INFO - Rotation: Supported 16:38:28 INFO - Return code: 0 16:38:28 INFO - Running post-action listener: _resource_record_post_action 16:38:28 INFO - Running post-run listener: _resource_record_post_run 16:38:29 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 36250112; Write bytes: 695681536; Read time: 375; Write time: 2065 16:38:29 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398539264; Write bytes: 387602432; Read time: 30349; Write time: 825 16:38:29 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29170176; Write bytes: 296532480; Read time: 290; Write time: 1206 16:38:29 INFO - Running post-run listener: _upload_blobber_files 16:38:29 INFO - Blob upload gear active. 16:38:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:29 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 16:38:29 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38:29 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:29 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:38:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:38:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:30 INFO - (blobuploader) - INFO - Done attempting. 16:38:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:38:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:38:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:31 INFO - (blobuploader) - INFO - Done attempting. 16:38:31 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:31 INFO - Return code: 0 16:38:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/41a6c27dace96e3ad7a7e30e3ffbff03d1beaa77e21fb4a3832ba4b1601c3219c070ec2506d6e63b066e9894349caf2aaef3ee901eef31c6d4e2629cca709cfe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72fda5bde497615ed571eaec8cdcba48989ef06e23114def1e1b2495cca02606cf2e414d147fa6276b52f8b9713db829c758e8cf8dea799c5f34bae2c7c772cb"} 16:38:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:31 INFO - Contents: 16:38:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/41a6c27dace96e3ad7a7e30e3ffbff03d1beaa77e21fb4a3832ba4b1601c3219c070ec2506d6e63b066e9894349caf2aaef3ee901eef31c6d4e2629cca709cfe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72fda5bde497615ed571eaec8cdcba48989ef06e23114def1e1b2495cca02606cf2e414d147fa6276b52f8b9713db829c758e8cf8dea799c5f34bae2c7c772cb"} 16:38:31 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:31 INFO - Copying logs to upload dir... 16:38:31 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.467135 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2016-04-26 16:38:31.498238) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 16:38:31.501594) ========= 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.sFRPbWs9PP/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.iRBT2zKejh/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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/Fx-Team/sha512/41a6c27dace96e3ad7a7e30e3ffbff03d1beaa77e21fb4a3832ba4b1601c3219c070ec2506d6e63b066e9894349caf2aaef3ee901eef31c6d4e2629cca709cfe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72fda5bde497615ed571eaec8cdcba48989ef06e23114def1e1b2495cca02606cf2e414d147fa6276b52f8b9713db829c758e8cf8dea799c5f34bae2c7c772cb"} build_url:https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011014 build_url: 'https://queue.taskcluster.net/v1/task/KL0jM5jCQaWQ04FX2h2Ndw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/41a6c27dace96e3ad7a7e30e3ffbff03d1beaa77e21fb4a3832ba4b1601c3219c070ec2506d6e63b066e9894349caf2aaef3ee901eef31c6d4e2629cca709cfe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/72fda5bde497615ed571eaec8cdcba48989ef06e23114def1e1b2495cca02606cf2e414d147fa6276b52f8b9713db829c758e8cf8dea799c5f34bae2c7c772cb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 16:38:31.529979) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:38:31.530347) ========= 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.sFRPbWs9PP/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.iRBT2zKejh/Listeners TMPDIR=/var/folders/s1/hcll5qd56kv3p0b9_8lpr4kw00000w/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.005117 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 16:38:31.581427) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 16:38:31.582094) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 16:38:31.582991) ========= ========= Total master_lag: 0.39 =========