builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0065 starttime: 1462537114.77 results: success (0) buildid: 20160506013122 builduid: edd2de8de4ca48969474bf5b1f92b92a revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.769315) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.769698) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.769966) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.791456) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.791713) ========= 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.WmBBG1Boal/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.cSGzUnDWDv/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-05-06 05:18:34-- 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% 38.6M=0s 2016-05-06 05:18:34 (38.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.900896) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.901167) ========= 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.WmBBG1Boal/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.cSGzUnDWDv/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.020845 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:34.951771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:18:34.952074) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b14d8e23323e95c5db3bc4cca69738edeee44302 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b14d8e23323e95c5db3bc4cca69738edeee44302 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WmBBG1Boal/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.cSGzUnDWDv/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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-05-06 05:18:35,009 truncating revision to first 12 chars 2016-05-06 05:18:35,009 Setting DEBUG logging. 2016-05-06 05:18:35,009 attempt 1/10 2016-05-06 05:18:35,010 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b14d8e23323e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 05:18:36,441 unpacking tar archive at: mozilla-aurora-b14d8e23323e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.678380 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 05:18:36.646924) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:36.647246) ========= script_repo_revision: b14d8e23323e95c5db3bc4cca69738edeee44302 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:36.650648) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:36.650959) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 05:18:36.664142) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-06 05:18:36.664434) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WmBBG1Boal/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.cSGzUnDWDv/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:18:36 INFO - MultiFileLogger online at 20160506 05:18:36 in /builds/slave/test 05:18:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:18:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:18:36 INFO - {'append_to_log': False, 05:18:36 INFO - 'base_work_dir': '/builds/slave/test', 05:18:36 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:18:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:18:36 INFO - 'branch': 'Mozilla-Aurora', 05:18:36 INFO - 'buildbot_json_path': 'buildprops.json', 05:18:36 INFO - 'config_files': ('talos/mac_config.py',), 05:18:36 INFO - 'default_actions': ('clobber', 05:18:36 INFO - 'read-buildbot-config', 05:18:36 INFO - 'download-and-extract', 05:18:36 INFO - 'populate-webroot', 05:18:36 INFO - 'create-virtualenv', 05:18:36 INFO - 'install', 05:18:36 INFO - 'run-tests'), 05:18:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:18:36 INFO - 'download_minidump_stackwalk': True, 05:18:36 INFO - 'download_symbols': 'ondemand', 05:18:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:18:36 INFO - 'tooltool.py': '/tools/tooltool.py', 05:18:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:18:36 INFO - '/tools/misc-python/virtualenv.py')}, 05:18:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:18:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:18:36 INFO - 'installer_path': 'installer.exe', 05:18:36 INFO - 'log_level': 'info', 05:18:36 INFO - 'log_name': 'talos', 05:18:36 INFO - 'log_to_console': True, 05:18:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:18:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:18:36 INFO - 'opt_config_files': (), 05:18:36 INFO - 'pip_index': False, 05:18:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:36 INFO - 'cmd': ('bash', 05:18:36 INFO - '-c', 05:18:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:36 INFO - 'enabled': True, 05:18:36 INFO - 'halt_on_failure': False, 05:18:36 INFO - 'name': 'check_screen_resolution'},), 05:18:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:18:36 INFO - 'cmd': ('bash', 05:18:36 INFO - '-c', 05:18:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:18:36 INFO - 'enabled': True, 05:18:36 INFO - 'halt_on_failure': False, 05:18:36 INFO - 'name': 'check_screen_resolution'},), 05:18:36 INFO - 'run_cmd_checks_enabled': True, 05:18:36 INFO - 'sps_profile': False, 05:18:36 INFO - 'sps_profile_interval': 0, 05:18:36 INFO - 'suite': 'dromaeojs-e10s', 05:18:36 INFO - 'system_bits': '32', 05:18:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:18:36 INFO - 'title': 't-yosemite-r7-0065', 05:18:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:18:36 INFO - 'use_talos_json': True, 05:18:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:18:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:18:36 INFO - 'work_dir': 'build'} 05:18:36 INFO - ##### 05:18:36 INFO - ##### Running clobber step. 05:18:36 INFO - ##### 05:18:36 INFO - Running pre-action listener: _resource_record_pre_action 05:18:36 INFO - Running main action method: clobber 05:18:36 INFO - rmtree: /builds/slave/test/build 05:18:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:18:37 INFO - Running post-action listener: _resource_record_post_action 05:18:37 INFO - ##### 05:18:37 INFO - ##### Running read-buildbot-config step. 05:18:37 INFO - ##### 05:18:37 INFO - Running pre-action listener: _resource_record_pre_action 05:18:37 INFO - Running main action method: read_buildbot_config 05:18:37 INFO - Using buildbot properties: 05:18:37 INFO - { 05:18:37 INFO - "project": "", 05:18:37 INFO - "product": "firefox", 05:18:37 INFO - "script_repo_revision": "production", 05:18:37 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:18:37 INFO - "repository": "", 05:18:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:18:37 INFO - "buildid": "20160506013122", 05:18:37 INFO - "pgo_build": "False", 05:18:37 INFO - "basedir": "/builds/slave/test", 05:18:37 INFO - "buildnumber": 287, 05:18:37 INFO - "slavename": "t-yosemite-r7-0065", 05:18:37 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:18:37 INFO - "platform": "yosemite_r7", 05:18:37 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:18:37 INFO - "branch": "mozilla-aurora", 05:18:37 INFO - "repo_path": "releases/mozilla-aurora", 05:18:37 INFO - "revision": "b14d8e23323e95c5db3bc4cca69738edeee44302", 05:18:37 INFO - "stage_platform": "macosx64", 05:18:37 INFO - "builduid": "edd2de8de4ca48969474bf5b1f92b92a", 05:18:37 INFO - "slavebuilddir": "test" 05:18:37 INFO - } 05:18:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:18:37 INFO - Running post-action listener: _resource_record_post_action 05:18:37 INFO - ##### 05:18:37 INFO - ##### Running download-and-extract step. 05:18:37 INFO - ##### 05:18:37 INFO - Running pre-action listener: _resource_record_pre_action 05:18:37 INFO - Running main action method: download_and_extract 05:18:37 INFO - mkdir: /builds/slave/test/build/tests 05:18:37 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')]} 05:18:37 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:18:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 05:18:38 INFO - Downloaded 1199 bytes. 05:18:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:18:38 INFO - Using the following test package requirements: 05:18:38 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:18:38 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:18:38 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:18:38 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'jsshell-mac.zip'], 05:18:38 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:18:38 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:18:38 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:18:38 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:18:38 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:18:38 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:18:38 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:18:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:38 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:39 INFO - Downloaded 18113035 bytes. 05:18:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:42 INFO - Return code: 0 05:18:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:42 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:18:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:18:43 INFO - Downloaded 18113035 bytes. 05:18:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:18:47 INFO - Return code: 0 05:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:47 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:18:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:18:48 INFO - Downloaded 10840145 bytes. 05:18:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:18:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:18:48 INFO - Return code: 0 05:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:18:48 INFO - https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:18:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:18:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 05:18:51 INFO - Downloaded 114079746 bytes. 05:18:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:51 INFO - mkdir: /builds/slave/test/properties 05:18:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:18:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:18:51 INFO - Contents: 05:18:51 INFO - build_url:https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:18:52 INFO - Running post-action listener: _resource_record_post_action 05:18:52 INFO - Running post-action listener: set_extra_try_arguments 05:18:52 INFO - ##### 05:18:52 INFO - ##### Running populate-webroot step. 05:18:52 INFO - ##### 05:18:52 INFO - Running pre-action listener: _resource_record_pre_action 05:18:52 INFO - Running main action method: populate_webroot 05:18:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:18:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:18:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:18:52 INFO - '--tppagecycles', 05:18:52 INFO - '7'], 05:18:52 INFO - 'tests': ['tsvgm']}}, 05:18:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:18:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:18:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:18:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:18:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:18:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['damp', 'tps']}, 05:18:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'tests': ['damp', 'tps']}, 05:18:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['dromaeo_dom']}, 05:18:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:18:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['a11yr', 05:18:52 INFO - 'ts_paint', 05:18:52 INFO - 'tpaint', 05:18:52 INFO - 'sessionrestore', 05:18:52 INFO - 'sessionrestore_no_auto_restore', 05:18:52 INFO - 'tabpaint']}, 05:18:52 INFO - 'other-e10s': {'tests': ['a11yr', 05:18:52 INFO - 'ts_paint', 05:18:52 INFO - 'tpaint', 05:18:52 INFO - 'sessionrestore', 05:18:52 INFO - 'sessionrestore_no_auto_restore', 05:18:52 INFO - 'tabpaint']}, 05:18:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:18:52 INFO - 'ts_paint', 05:18:52 INFO - 'tpaint', 05:18:52 INFO - 'sessionrestore', 05:18:52 INFO - 'sessionrestore_no_auto_restore', 05:18:52 INFO - 'tabpaint']}, 05:18:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:18:52 INFO - 'ts_paint', 05:18:52 INFO - 'tpaint', 05:18:52 INFO - 'sessionrestore', 05:18:52 INFO - 'sessionrestore_no_auto_restore', 05:18:52 INFO - 'tabpaint']}, 05:18:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['a11yr', 05:18:52 INFO - 'ts_paint', 05:18:52 INFO - 'tpaint', 05:18:52 INFO - 'sessionrestore', 05:18:52 INFO - 'sessionrestore_no_auto_restore', 05:18:52 INFO - 'tabpaint']}, 05:18:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['a11yr', 05:18:52 INFO - 'ts_paint', 05:18:52 INFO - 'tpaint', 05:18:52 INFO - 'sessionrestore', 05:18:52 INFO - 'sessionrestore_no_auto_restore', 05:18:52 INFO - 'tabpaint']}, 05:18:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['tsvgx', 05:18:52 INFO - 'tsvgr_opacity', 05:18:52 INFO - 'tart', 05:18:52 INFO - 'tscrollx', 05:18:52 INFO - 'cart']}, 05:18:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:18:52 INFO - 'tsvgr_opacity', 05:18:52 INFO - 'tart', 05:18:52 INFO - 'tscrollx', 05:18:52 INFO - 'cart']}, 05:18:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'talos_options': ['--disable-e10s'], 05:18:52 INFO - 'tests': ['tp5o']}, 05:18:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'tests': ['tp5o']}, 05:18:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'talos_options': ['--disable-e10s', 05:18:52 INFO - '--xperf_path', 05:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:52 INFO - 'tests': ['tp5n']}, 05:18:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:18:52 INFO - 'talos_options': ['--xperf_path', 05:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:18:52 INFO - 'tests': ['tp5n']}}, 05:18:52 INFO - 'talos.zip': {'path': '', 05:18:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:18:52 INFO - Running post-action listener: _resource_record_post_action 05:18:52 INFO - ##### 05:18:52 INFO - ##### Running create-virtualenv step. 05:18:52 INFO - ##### 05:18:52 INFO - Running pre-action listener: _resource_record_pre_action 05:18:52 INFO - Running main action method: create_virtualenv 05:18:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:18:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:18:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:18:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:18:52 INFO - Using real prefix '/tools/python27' 05:18:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:18:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:18:56 INFO - Installing pip.................done. 05:18:56 INFO - Return code: 0 05:18:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:56 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:56 INFO - 'HOME': '/Users/cltbld', 05:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:56 INFO - 'LOGNAME': 'cltbld', 05:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:56 INFO - 'MOZ_NO_REMOTE': '1', 05:18:56 INFO - 'NO_EM_RESTART': '1', 05:18:56 INFO - 'PAGER': '/bin/cat', 05:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:56 INFO - 'PWD': '/builds/slave/test', 05:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:56 INFO - 'SHELL': '/bin/bash', 05:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:18:56 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:56 INFO - 'USER': 'cltbld', 05:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:56 INFO - 'XPC_FLAGS': '0x0', 05:18:56 INFO - 'XPC_SERVICE_NAME': '0', 05:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:18:56 INFO - Cleaning up... 05:18:56 INFO - Return code: 0 05:18:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:18:56 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:18:56 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 05:18:56 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 05:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:18:56 INFO - 'HOME': '/Users/cltbld', 05:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:18:56 INFO - 'LOGNAME': 'cltbld', 05:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:18:56 INFO - 'MOZ_NO_REMOTE': '1', 05:18:56 INFO - 'NO_EM_RESTART': '1', 05:18:56 INFO - 'PAGER': '/bin/cat', 05:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:18:56 INFO - 'PWD': '/builds/slave/test', 05:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:18:56 INFO - 'SHELL': '/bin/bash', 05:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:18:56 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:18:56 INFO - 'USER': 'cltbld', 05:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:18:56 INFO - 'XPC_FLAGS': '0x0', 05:18:56 INFO - 'XPC_SERVICE_NAME': '0', 05:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:18:57 INFO - Downloading/unpacking psutil>=0.7.1 05:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:18:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:18:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:18:59 INFO - Installing collected packages: psutil 05:18:59 INFO - Running setup.py install for psutil 05:19:00 INFO - building 'psutil._psutil_osx' extension 05:19:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:19:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:19:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:19:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:19:00 INFO - building 'psutil._psutil_posix' extension 05:19:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:19:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:19:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:19:00 INFO - ^ 05:19:00 INFO - 1 warning generated. 05:19:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:19:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:19:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:19:00 INFO - Successfully installed psutil 05:19:00 INFO - Cleaning up... 05:19:00 INFO - Return code: 0 05:19:00 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:00 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:00 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 05:19:00 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 05:19:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:19:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:00 INFO - 'HOME': '/Users/cltbld', 05:19:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:00 INFO - 'LOGNAME': 'cltbld', 05:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:00 INFO - 'MOZ_NO_REMOTE': '1', 05:19:00 INFO - 'NO_EM_RESTART': '1', 05:19:00 INFO - 'PAGER': '/bin/cat', 05:19:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:00 INFO - 'PWD': '/builds/slave/test', 05:19:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:00 INFO - 'SHELL': '/bin/bash', 05:19:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:19:00 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:19:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:00 INFO - 'USER': 'cltbld', 05:19:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:00 INFO - 'XPC_FLAGS': '0x0', 05:19:00 INFO - 'XPC_SERVICE_NAME': '0', 05:19:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:19:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:19:03 INFO - Installing collected packages: mozsystemmonitor 05:19:03 INFO - Running setup.py install for mozsystemmonitor 05:19:03 INFO - Successfully installed mozsystemmonitor 05:19:03 INFO - Cleaning up... 05:19:03 INFO - Return code: 0 05:19:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:19:03 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')]} 05:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:03 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')]} 05:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:03 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:03 INFO - 'HOME': '/Users/cltbld', 05:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:03 INFO - 'LOGNAME': 'cltbld', 05:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:03 INFO - 'MOZ_NO_REMOTE': '1', 05:19:03 INFO - 'NO_EM_RESTART': '1', 05:19:03 INFO - 'PAGER': '/bin/cat', 05:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:03 INFO - 'PWD': '/builds/slave/test', 05:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:03 INFO - 'SHELL': '/bin/bash', 05:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:19:03 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:03 INFO - 'USER': 'cltbld', 05:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:03 INFO - 'XPC_FLAGS': '0x0', 05:19:03 INFO - 'XPC_SERVICE_NAME': '0', 05:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:19:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:19:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:19:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19: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 05:19:07 INFO - Downloading docopt-0.6.1.tar.gz 05:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:19:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:19:07 INFO - Running setup.py install for blobuploader 05:19:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:19:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:19:07 INFO - Running setup.py install for requests 05:19:08 INFO - Running setup.py install for docopt 05:19:08 INFO - Successfully installed blobuploader requests docopt 05:19:08 INFO - Cleaning up... 05:19:08 INFO - Return code: 0 05:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:08 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:08 INFO - 'HOME': '/Users/cltbld', 05:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:08 INFO - 'LOGNAME': 'cltbld', 05:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:08 INFO - 'MOZ_NO_REMOTE': '1', 05:19:08 INFO - 'NO_EM_RESTART': '1', 05:19:08 INFO - 'PAGER': '/bin/cat', 05:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:08 INFO - 'PWD': '/builds/slave/test', 05:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:08 INFO - 'SHELL': '/bin/bash', 05:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:19:08 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:08 INFO - 'USER': 'cltbld', 05:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:08 INFO - 'XPC_FLAGS': '0x0', 05:19:08 INFO - 'XPC_SERVICE_NAME': '0', 05:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:08 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-RUfgf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-EXa4JY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-mhSs5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-d3UsHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-rD6GkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-9SkR6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-adDqo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-rQrpq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-TNrmpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-l1u3Fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:09 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Taw7oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-4NGMZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-vK0sAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-TuqU4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-GiSlZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ZItDAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:10 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-tFMQ_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:19:10 INFO - Running setup.py install for manifestparser 05:19:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:19:10 INFO - Running setup.py install for mozcrash 05:19:10 INFO - Running setup.py install for mozdebug 05:19:11 INFO - Running setup.py install for mozdevice 05:19:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Running setup.py install for mozfile 05:19:11 INFO - Running setup.py install for mozhttpd 05:19:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Running setup.py install for mozinfo 05:19:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Running setup.py install for mozInstall 05:19:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:19:11 INFO - Running setup.py install for mozleak 05:19:11 INFO - Running setup.py install for mozlog 05:19:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:19:12 INFO - Running setup.py install for moznetwork 05:19:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:19:12 INFO - Running setup.py install for mozprocess 05:19:12 INFO - Running setup.py install for mozprofile 05:19:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:19:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:19:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:19:12 INFO - Running setup.py install for mozrunner 05:19:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:19:12 INFO - Running setup.py install for mozscreenshot 05:19:12 INFO - Running setup.py install for moztest 05:19:13 INFO - Running setup.py install for mozversion 05:19:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:19:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:19:13 INFO - Cleaning up... 05:19:13 INFO - Return code: 0 05:19:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:13 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:13 INFO - 'HOME': '/Users/cltbld', 05:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:13 INFO - 'LOGNAME': 'cltbld', 05:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:13 INFO - 'MOZ_NO_REMOTE': '1', 05:19:13 INFO - 'NO_EM_RESTART': '1', 05:19:13 INFO - 'PAGER': '/bin/cat', 05:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:13 INFO - 'PWD': '/builds/slave/test', 05:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:13 INFO - 'SHELL': '/bin/bash', 05:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:19:13 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:13 INFO - 'USER': 'cltbld', 05:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:13 INFO - 'XPC_FLAGS': '0x0', 05:19:13 INFO - 'XPC_SERVICE_NAME': '0', 05:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:19:13 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-w9XXdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:19:13 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-wmz7Gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:19:13 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)) 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:19:13 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-S9FxcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:19:13 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-CJBnKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:19:13 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Ptb3cR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-guAoDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-imfdkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-lPJovJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-gVewuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-XDV_V3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:19:14 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)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-dGjuLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-otm8AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:19:14 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)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Pusas1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:19:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:19:14 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Cs07c_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:19:15 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)) 05:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:15 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-QXUM8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:19:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:19:15 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-ZzfzIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:19:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:19:15 INFO - Running setup.py (path:/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/pip-Ei403i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:19:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:19:15 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)) 05:19:15 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)) 05:19:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:19:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:19:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:17 INFO - Downloading blessings-1.6.tar.gz 05:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:19:17 INFO - Installing collected packages: blessings 05:19:17 INFO - Running setup.py install for blessings 05:19:18 INFO - Successfully installed blessings 05:19:18 INFO - Cleaning up... 05:19:18 INFO - Return code: 0 05:19:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:19:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:18 INFO - Reading from file tmpfile_stdout 05:19:18 INFO - Current package versions: 05:19:18 INFO - blessings == 1.6 05:19:18 INFO - blobuploader == 1.2.4 05:19:18 INFO - docopt == 0.6.1 05:19:18 INFO - manifestparser == 1.1 05:19:18 INFO - mozInstall == 1.12 05:19:18 INFO - mozcrash == 0.17 05:19:18 INFO - mozdebug == 0.1 05:19:18 INFO - mozdevice == 0.48 05:19:18 INFO - mozfile == 1.2 05:19:18 INFO - mozhttpd == 0.7 05:19:18 INFO - mozinfo == 0.9 05:19:18 INFO - mozleak == 0.1 05:19:18 INFO - mozlog == 3.1 05:19:18 INFO - moznetwork == 0.27 05:19:18 INFO - mozprocess == 0.22 05:19:18 INFO - mozprofile == 0.28 05:19:18 INFO - mozrunner == 6.11 05:19:18 INFO - mozscreenshot == 0.1 05:19:18 INFO - mozsystemmonitor == 0.0 05:19:18 INFO - moztest == 0.7 05:19:18 INFO - mozversion == 1.4 05:19:18 INFO - psutil == 3.1.1 05:19:18 INFO - requests == 1.2.3 05:19:18 INFO - wsgiref == 0.1.2 05:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:18 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:18 INFO - 'HOME': '/Users/cltbld', 05:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:18 INFO - 'LOGNAME': 'cltbld', 05:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:18 INFO - 'MOZ_NO_REMOTE': '1', 05:19:18 INFO - 'NO_EM_RESTART': '1', 05:19:18 INFO - 'PAGER': '/bin/cat', 05:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:18 INFO - 'PWD': '/builds/slave/test', 05:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:18 INFO - 'SHELL': '/bin/bash', 05:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:19:18 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:18 INFO - 'USER': 'cltbld', 05:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:18 INFO - 'XPC_FLAGS': '0x0', 05:19:18 INFO - 'XPC_SERVICE_NAME': '0', 05:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:19:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:19:18 INFO - Cleaning up... 05:19:18 INFO - Return code: 0 05:19:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:18 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:18 INFO - 'HOME': '/Users/cltbld', 05:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:18 INFO - 'LOGNAME': 'cltbld', 05:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:18 INFO - 'MOZ_NO_REMOTE': '1', 05:19:18 INFO - 'NO_EM_RESTART': '1', 05:19:18 INFO - 'PAGER': '/bin/cat', 05:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:18 INFO - 'PWD': '/builds/slave/test', 05:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:18 INFO - 'SHELL': '/bin/bash', 05:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:19:18 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:18 INFO - 'USER': 'cltbld', 05:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:18 INFO - 'XPC_FLAGS': '0x0', 05:19:18 INFO - 'XPC_SERVICE_NAME': '0', 05:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:18 INFO - Downloading/unpacking jsonschema 05:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:19:21 INFO - Downloading/unpacking functools32 (from jsonschema) 05:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:21 INFO - Downloading functools32-3.2.3-2.tar.gz 05:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:19:21 INFO - warning: no files found matching '*.txt' 05:19:21 INFO - no previously-included directories found matching 'build' 05:19:21 INFO - no previously-included directories found matching 'dist' 05:19:21 INFO - no previously-included directories found matching '.git*' 05:19:21 INFO - Installing collected packages: jsonschema, functools32 05:19:21 INFO - Running setup.py install for functools32 05:19:22 INFO - warning: no files found matching '*.txt' 05:19:22 INFO - no previously-included directories found matching 'build' 05:19:22 INFO - no previously-included directories found matching 'dist' 05:19:22 INFO - no previously-included directories found matching '.git*' 05:19:22 INFO - Successfully installed jsonschema functools32 05:19:22 INFO - Cleaning up... 05:19:22 INFO - Return code: 0 05:19:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:19:22 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')]} 05:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:22 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')]} 05:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:19:22 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 0x105ef3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f03420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb0257bb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, '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.WmBBG1Boal/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.cSGzUnDWDv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:19:22 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 05:19:22 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 05:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:19:22 INFO - 'HOME': '/Users/cltbld', 05:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:19:22 INFO - 'LOGNAME': 'cltbld', 05:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:19:22 INFO - 'MOZ_NO_REMOTE': '1', 05:19:22 INFO - 'NO_EM_RESTART': '1', 05:19:22 INFO - 'PAGER': '/bin/cat', 05:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:19:22 INFO - 'PWD': '/builds/slave/test', 05:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:19:22 INFO - 'SHELL': '/bin/bash', 05:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:19:22 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:19:22 INFO - 'USER': 'cltbld', 05:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:19:22 INFO - 'XPC_FLAGS': '0x0', 05:19:22 INFO - 'XPC_SERVICE_NAME': '0', 05:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:19:22 INFO - Downloading/unpacking flake8 05:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:19:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:19:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:19:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:26 INFO - no previously-included directories found matching 'docs/_build' 05:19:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:19:26 INFO - Running setup.py install for pep8 05:19:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:19:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:19:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:19:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:19:26 INFO - no previously-included directories found matching 'docs/_build' 05:19:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:19:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:19:26 INFO - Cleaning up... 05:19:26 INFO - Return code: 0 05:19:26 INFO - Running post-action listener: _resource_record_post_action 05:19:26 INFO - Running post-action listener: _start_resource_monitoring 05:19:26 INFO - Starting resource monitoring. 05:19:26 INFO - ##### 05:19:26 INFO - ##### Running install step. 05:19:26 INFO - ##### 05:19:26 INFO - Running pre-action listener: _resource_record_pre_action 05:19:26 INFO - Running main action method: install 05:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:19:26 INFO - Reading from file tmpfile_stdout 05:19:26 INFO - Detecting whether we're running mozinstall >=1.0... 05:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:19:26 INFO - Reading from file tmpfile_stdout 05:19:26 INFO - Output received: 05:19:26 INFO - Usage: mozinstall [options] installer 05:19:26 INFO - Options: 05:19:26 INFO - -h, --help show this help message and exit 05:19:26 INFO - -d DEST, --destination=DEST 05:19:26 INFO - Directory to install application into. [default: 05:19:26 INFO - "/builds/slave/test"] 05:19:26 INFO - --app=APP Application being installed. [default: firefox] 05:19:26 INFO - mkdir: /builds/slave/test/build/application 05:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:19:58 INFO - Reading from file tmpfile_stdout 05:19:58 INFO - Output received: 05:19:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:19:58 INFO - Running post-action listener: _resource_record_post_action 05:19:58 INFO - ##### 05:19:58 INFO - ##### Running run-tests step. 05:19:58 INFO - ##### 05:19:58 INFO - Running pre-action listener: _resource_record_pre_action 05:19:58 INFO - Running main action method: run_tests 05:19:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:58 INFO - 2016-05-06 05:19:58.888 screenresolution[2067:12078] starting screenresolution argv=screenresolution get 05:19:58 INFO - 2016-05-06 05:19:58.891 screenresolution[2067:12078] Display 0: 1600x1200x32@60 05:19:58 INFO - 2016-05-06 05:19:58.897 screenresolution[2068:12080] starting screenresolution argv=screenresolution list 05:19:58 INFO - Available Modes on Display 0 05:19:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:59 INFO - Intel Iris: 05:19:59 INFO - Chipset Model: Intel Iris 05:19:59 INFO - Type: GPU 05:19:59 INFO - Bus: Built-In 05:19:59 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:59 INFO - Vendor: Intel (0x8086) 05:19:59 INFO - Device ID: 0x0a2e 05:19:59 INFO - Revision ID: 0x0009 05:19:59 INFO - Displays: 05:19:59 INFO - Display: 05:19:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:59 INFO - Display Serial Number: 1600x1200 60 05:19:59 INFO - Main Display: Yes 05:19:59 INFO - Mirror: Off 05:19:59 INFO - Online: Yes 05:19:59 INFO - Rotation: Supported 05:19:59 INFO - Return code: 0 05:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:19:59 INFO - Python 2.7.3 05:19:59 INFO - Return code: 0 05:19:59 INFO - grabbing minidump binary from tooltool 05:19:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:19:59 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d92e00>, '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 0x105bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d8db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:19:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:19:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:19:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:19:59 INFO - Return code: 0 05:19:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:19:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:19:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:19:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:19:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:20:00 INFO - Return code: 0 05:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WmBBG1Boal/Render', 05:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:20:00 INFO - 'HOME': '/Users/cltbld', 05:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:20:00 INFO - 'LOGNAME': 'cltbld', 05:20:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:20:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:20:00 INFO - 'MOZ_NO_REMOTE': '1', 05:20:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:20:00 INFO - 'NO_EM_RESTART': '1', 05:20:00 INFO - 'PAGER': '/bin/cat', 05:20:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:20:00 INFO - 'PWD': '/builds/slave/test', 05:20:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:20:00 INFO - 'SHELL': '/bin/bash', 05:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cSGzUnDWDv/Listeners', 05:20:00 INFO - 'TMPDIR': '/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/', 05:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:20:00 INFO - 'USER': 'cltbld', 05:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:20:00 INFO - 'XPC_FLAGS': '0x0', 05:20:00 INFO - 'XPC_SERVICE_NAME': '0', 05:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:20:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:20:00 INFO - mozversion application_buildid: 20160506013122 05:20:00 INFO - mozversion application_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:20:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:20:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:20:00 INFO - mozversion application_name: Firefox 05:20:00 INFO - mozversion application_remotingname: firefox-dev 05:20:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:00 INFO - mozversion application_vendor: Mozilla 05:20:00 INFO - mozversion application_version: 48.0a2 05:20:00 INFO - mozversion platform_buildid: 20160506013122 05:20:00 INFO - mozversion platform_changeset: b14d8e23323e95c5db3bc4cca69738edeee44302 05:20:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:20:00 INFO - mozversion platform_version: 48.0a2 05:20:00 INFO - using testdate: 1462537200 05:20:00 INFO - actual date: 1462537200 05:20:00 INFO - starting webserver on 'localhost:49358' 05:20:00 INFO - SUITE-START | Running 2 tests 05:20:00 INFO - TEST-START | dromaeo_css 05:20:00 INFO - operating with platform_type : mac_ 05:20:00 INFO - Initialising browser for dromaeo_css test... 05:20:00 INFO - TEST-INFO | started process 2086 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpswM9LM/profile http://localhost:49358/getInfo.html) 05:20:03 INFO - PROCESS | 2086 | __metrics Screen width/height:1600/1200 05:20:03 INFO - PROCESS | 2086 | colorDepth:24 05:20:03 INFO - PROCESS | 2086 | Browser inner width/height: 1024/689 05:20:03 INFO - PROCESS | 2086 | __metrics 05:20:03 ERROR - PROCESS | 2086 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:20:04 INFO - TEST-INFO | 2086: exit 0 05:20:04 INFO - Browser initialized. 05:20:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:20:04 INFO - TEST-INFO | started process 2090 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpswM9LM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:16 INFO - PROCESS | 2090 | RSS: Main: 221052928 05:20:16 INFO - PROCESS | 2090 | 05:21:05 INFO - PROCESS | 2090 | 1462537265692 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:23:16 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49358/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49358/tests/dromaeo/cssquery-ext.html) 05:23:17 INFO - PROCESS | 2090 | RSS: Main: 231612416 05:23:17 INFO - PROCESS | 2090 | 05:26:19 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49358/tests/dromaeo/cssquery-ext.html (next: http://localhost:49358/tests/dromaeo/cssquery-jquery.html) 05:26:20 INFO - PROCESS | 2090 | RSS: Main: 243404800 05:26:20 INFO - PROCESS | 2090 | 05:29:22 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49358/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49358/tests/dromaeo/cssquery-mootools.html) 05:29:23 INFO - PROCESS | 2090 | RSS: Main: 245198848 05:29:23 INFO - PROCESS | 2090 | 05:32:24 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49358/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49358/tests/dromaeo/cssquery-prototype.html) 05:32:25 INFO - PROCESS | 2090 | RSS: Main: 247164928 05:32:25 INFO - PROCESS | 2090 | 05:35:28 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49358/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49358/tests/dromaeo/cssquery-yui.html) 05:35:28 INFO - PROCESS | 2090 | RSS: Main: 250081280 05:35:28 INFO - PROCESS | 2090 | 05:38:30 INFO - PROCESS | 2090 | Cycle 1(1): loaded http://localhost:49358/tests/dromaeo/cssquery-yui.html (next: http://localhost:49358/tests/dromaeo/cssquery-dojo.html) 05:38:31 INFO - PROCESS | 2090 | RSS: Main: 250953728 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | __start_tp_report 05:38:31 INFO - PROCESS | 2090 | _x_x_mozilla_page_load 05:38:31 INFO - PROCESS | 2090 | _x_x_mozilla_page_load_details 05:38:31 INFO - PROCESS | 2090 | |i|pagename|runs| 05:38:31 INFO - PROCESS | 2090 | |0;dojo.html;2968.4953557895155;2971.9405611887755;3447.1554469155053;3482.3557641836246;3535.434330507118;5774.335951365594;5783.334916484606;5785.7685692572295;5814.360420353763;5815.360310365856;5776.075827867542;5817.912731309023;5838.065909454488;5861.912071318934;5880.764769409229;5264.315638966938;5268.841934741964;5338.466153384669;5349.652272602269;5353.491418315254;5180.844574662748;5302.973485132569;5312.814051508198;5402.864928376803;5416.91874621881;5223.503767142134;5261.158214685651;5290;5301.655392399501;5313.814016509423;5206.609504287194;5235.528802407764;5334.786608535654;5341.439148889388;5377.3009508764;5237.580993520509;5280.2079688046715;5309.4425085365865;5318.813841515567;5338.813141540028;5474.972625136849;5590.105583106683;5599.1041433370465;5744.913826292567;5829.8251052468495;6197.473214776749;6200.968995154995;6248.1564988726395;6262.812115636492;6263.060540918898;5275.261463395128;6275.780347687854;6282.120503129566;6282.623042617412;6305.59013664115;5736.598438109292;5787.39232380594;5838.9124163137585;5855.502282306052;5945.851353716149;6305.085762564402;6345.206849143857;6359.395857393541;6359.682015899187;6370;6192.164057852222;6225.875482490324;6230.470410015108;6232.439080482778;6240;5150.510701483428;6152.323244443108;6215.564910456315;6315.684215789192;6334.968325158345;6190.628562286277;6263.906041409383;6267.373262673696;6278.937210627927;6282;6288.151099601587;6307.936920630735;6313.3686631336495;6325.873482530324;6329.92391293472;4818.71087734737;4966.577840883493;5018.723970181602;5020.623453241022;5064.139096353555;3974.926769772203;4470.977645111753;4513.593776560126;4516.706414083074;4536.841210557614;4651;4923.876903077452;5570.916436253459;5735.340435849931;5834.387389324144;6136.3965394158;6191.783287585003;6230.127782110508;6248.937510624926;6323.557350985387;5856.912146317809;5868.207791948118;5892.852678682982;5902.852428689232;5932.970335148297;5332.6000549958135;5490.313710786152;5502.064649009598;5505.752241149158;5507.697076660822;6353.364663533702;6428.132202152743;6437.452816510649;6443.613383197023;6452.806415807533;6301.212348456443;6310.652914089769;6313.43179113882;6322.905156422566;6325.873482530232;6254.312025677263;6390.233172019387;6484.610923344614;6515.54391192612;6515.73937042513;6197.25632924052;6262.812115636538;6264.34224406449;6265.4361107500545;6298.338674439118;6071.089336599545;6255.749770009149;6268.7492500301305;6276.8430789228805;6293.118963345044;6059.545534084873;6134.130457564626;6170.167027451416;6199.690015499117;6211.65835879031;6193.535484838565;6200.038993955944;6203.193584833943;6208.751649934133;6217.409346112112;5967.401687629407;6057.697115144137;6087.8782424350375;6130.141780150693;6155.784547540815;5932.733027013881;6127;6162.291336496361;6203.968980155251;6221.595596286227;5628.183913332626;6079;6103.169049285286;6103.585023804053;6108.72510737009 05:38:31 INFO - PROCESS | 2090 | |1;ext.html;493.935788347515;511.5319482673354;582.7261187242;594.0584174084076;618.0266080922545;38515;39576.40635390471;39606;39629.60370396295;39780.403293950614;40646.79676601613;40933.795331023306;41521.75434736963;41590.58409415905;41600.37599436011;9410.576524056416;9589.568469418893;9652;9658.806823863519;9671.70984870455;9470.195033422167;9692.41845489273;9724.70825875225;9731.416115033086;9782.119609235167;9703.805923881519;9746.707598772047;9763.609455621767;9782.608695652201;9786.363886347364;8282.337413006944;9647;9691.466969316685;9717.514124293792;9750.512474376288;9690.51547422626;9791;9801.656942006995;9807.90192098077;9834.459104749307;37817.62182378169;39641.80179099115;39853;40138.79930600328;40335.39496907519;2998.7900846940724;3209.7753157279;3361.3613413451367;3361.781484203519;3490.232148927257;28665.856670716512;29235.707642923724;29244.41511169786;29356;29456.705432945826;2776.986115069432;2778.9305267368277;2788.6793018802896;2820.478211530853;2830.3348713052624;485441;491072;492076.5396173032;492658.9999999964;494560;32011.87958421444;32267.193320167185;35495.4675679865;66062;66602;36042.63957360393;36163.276734465166;36330.636693633256;36337;36444.817775911484;15947;16193.919030404773;16195.43315983935;16202.189890505428;16243.51269461918;13452.394642241123;13517.391717372742;13540.661483462794;13552.051356405154;13588.184708917497;8304.667813287522;8333.499990000619;8394.24451799341;8434.325253979667;8439.451435656663;3436.295559410315;3493.7554371193773;3504.8773292935134;3506.4915587239707;3506.8246587670515;935.1069728409353;973.3089506450358;1021.1779517488493;1090.4438736244572;1095.1403148528527;28422;30633;30686.846565767028;30792.50598955348;30991;1121.5401685309148;1142.5886680794906;1144.2905398652888;1144.5650652751901;1175.4534141624015;12674.112812103249;15696.607584810472;15909.840901590836;16023.19884005795;16061.35218552291;617757;619439;625980;627372.8631356906;629513;60404;62309;62331.688341558;62348.75302494015;62590;1252.5866464066837;1515.9393624254906;1517.6281810956166;1519.4226194046191;1524.3826250368618;29506.55740163856;29843.552346714383;29891;30513.54229686601;30654.773809047387;54184;54481.72759136179;54941;55247;55510;578631;580057;580819;582602;582781;27702;27755.306117347227;27766;27965.021224257052;28039.29901752391;48153.5184648149;48409.515904840504;48710.75644621754;48805.267920981925;48913.75543122261;39150.80424597859;39567.40648890211;39713.80143099266;39756.801215993735;40606.39090413587;38395.84812455631;38697.80651096727;38704;39147.608523914394;39476;35874.641253588175;36702.63297367099;37052.44421333628;37137.25725485529;38805.02987425224;29729.85135074311;31823.68176318207;33175.83412082924;33231;33366.33267334591;24804.255872323858;25308.873455632605;25344.239672809847;25526.361840954123;25727 05:38:31 INFO - PROCESS | 2090 | |2;jquery.html;397.1837873170635;400.095783529224;401.39589917174646;406.4005591752164;411.4835880969383;11168.933747256764;14881.553553393389;15884.920575397136;20133.798662013374;21391;16606.418775342867;19840.503987400323;20277.493062673442;20309.593808123827;20775.37673869786;5726.942730572703;5839.824805255838;6037.6075555089;6067.696615169224;6074.848128796772;5154.943236636481;5664.716764161796;5872.324682661484;5892.705364731768;5992.43071908168;5682.51698605619;5923.348431672513;5962.433568810956;5983.371745966676;5989.730462129193;5085.84742457725;5881.441263080043;5943.732532036091;5949.077892926603;5956.731947062371;5068.1329189576945;5119.360079990001;5169.147090730025;5176.50823171798;5186.948130518685;24915;25648.845801938773;26055.73944260552;26230.606540901903;26620.73379266202;1948.5580670304014;2157.7194964654495;2444.4744379958393;2458.840175388594;2583.5866261398273;9593.616255349847;9661.420314781066;9721.17370023549;9791.167750741195;9969.052939970692;1674.1963857348542;1730.134932533733;1734.2802736864187;1765.6380442009358;1834.5230240137528;64118;119763;121024.57950840934;123479;123498.38250808779;11501.53993840237;11877.940610297066;11890.167688261912;11931;11935.462904169333;15072.623184420478;15083.170425626477;15091.924540377227;15102.244887755787;15113.924430377778;8771.079036701181;8864.556772161366;8873.822523549492;8945.46327220369;8985.46087234768;7490.576790409805;8595.269402100766;8868.33487488441;8909.465432074096;9003.77490562728;4558.1795276849825;4560.201964656172;4648.6745927785405;4648.953510464852;4668;1678.7985441747069;1733.479956013191;1979.7822239553925;2007.1469625409143;2018.1120307064843;816.6692489541745;877.4559772940818;894.673444192865;1011.5296387179997;1013.3463915774431;19557.31549395764;19986.300479483147;20191.394258172277;20462.29367245542;20475.18099276042;930.8231436027133;934.56075644447;985.940843549375;1026.9884164098357;1050.4537640426936;10488.790224195473;10799.920007999137;10805.919408059288;10806.51370688321;10811.459427028618;521039;521119;530328;599892;641252;105828.47085764675;129364;132918;138783;142610;1007.6171054999201;1026.984595231072;1030.8247597908373;1051.9789604207872;1057.3655806516183;14127.505537306144;17443.738343925106;17618.29526818913;17822.019788911733;17896.10519473995;35241.64758352384;40161.39757903575;42399.788001059795;42696;42860.6426646525;56604.71697641486;59892.10161847663;62560;72655.63672181606;77080;9193.310501712265;10465.372077675363;11668.766624667289;12066.879331206575;12156.209846359958;13031;13094.54169104115;13161.868381316448;13171.868281317447;13343.132696374705;9978.351407158512;10399.480025998519;10487.632932847313;11345.149113816662;11376.601818936297;7060.717571297296;8108.75673729789;9479.336446448682;11358.375289359165;11611.477483513094;10581.735456613646;11440.599579014695;11709.531618735155;11916.225445346025;11979.940100299442;12137.089718271342;12176.939115304367;12177.512899484276;12384.75230495367;12441.377931103227;11367.772644546898;11464.31214127155;11579.363135027654;11696.88303116958;11865.644030679094 05:38:31 INFO - PROCESS | 2090 | |3;mootools.html;1599.8800089993254;1618.4254589620684;1625.016864796798;1786.5087101047218;1821.1531637788426;7321.304476074771;7895.131535531087;8458.407911446202;8812.955935220323;8835.734927952164;8450.87323690145;8478.025027121883;8576.185262400062;8577.013643431008;8594.95702521488;2443.2670198940336;2456.7666071723156;2483.4908843687;2483.863387513686;2487.4030232744194;2044.2640649366217;2440.2313271319567;2475.220305603738;2491.1530079772874;2495.737947515512;2466.0628960994795;2478.9876050619723;2484.7266800651914;2485.0184177249976;2507.924762257135;2462.704475462947;2463.655088287632;2487.1046423287603;2492.538880307149;2494.9750502494926;2355.4346956730433;2446.1316232737477;2457.987710061438;2465.506898620283;2496.3135137837057;8614.655413783441;8708.303335733126;8719.433236839584;8724.258438032775;8768.736937891874;5324.92012619811;5448.564114870801;5465.180222966547;5504.2018907258225;5538.196961440569;3544.521489598923;3560.786352818839;3580.2839432113683;3664.2121943782213;3675.7426980111404;1029.6962396093095;1051.5110473629722;1056.609054649777;1069.1607088435542;1075.3601607043797;11561.075113990944;11607.709807254801;11666.416679166008;11701.297922124615;11714.414279286002;4852.757362131879;4860.149473842063;4979;4993.400791904995;5021.84934451967;9915.008499150028;9937.80124397508;9984.151347135576;9997.400155990665;10047.296689231684;5387.380451248078;5410.810621628224;5473.28847249855;5494.2582751328055;5523.696196709219;3954.6440820326306;4445.466544014739;4928.581070609039;4957.281194226817;5028.547430731252;4102.979485102555;4131.170148448263;4147.585241475828;4185.037441388497;4185.330347144442;1353.78339465685;1381.1367895065584;1386.8821150202346;1392.5822253324166;1396;901.3336766985518;909.1590278991906;920.9079092090872;931.8229536388064;934.2525979216599;5669.943300567024;5741.799037033684;5821.592488525763;5838.12428135783;6069.605475644069;851.1913682001999;945.7588314979773;961.6393852305386;992.7220378294089;1008.2438171371472;3697.5932647408763;3756;3761.5298087739034;3858.5369755629504;3912.7065470089856;8636.438631488933;9923.206143508502;10241.539130739135;10389.324693895023;10406.323588966692;10339.534720937576;10441.738956526147;10486.423246721504;10553.314034587727;10632.255742098134;7863.292303692695;8118.02583689961;8160;8227.917720822836;8268.917310826815;4752.158491390917;5538.556915446835;5613.101903695389;5786.218860453785;5858.619189752652;9531.142197202285;9753.317267791186;9810.75473113178;9841.45871977048;9915.405075695482;10011.699649010541;10027.696399468023;10157.593696252363;10184.847227291744;10216.897831021595;6405.487560995202;6578.407943285128;7491.775246742606;7549.433792465477;7644.80887977805;8066;8135.511869287861;8162.510249384818;8164.183581641788;8165.346772258324;7211.67547460355;7444.664990075351;7450.105987281561;7500.32497075244;7568.091828980558;7333.046703928455;7396.4822462429265;7418.9629051856555;7485.737999170003;7538.698452061856;6857.245702972768;6918.965405173143;6939.826504337432;6953.26990665993;6960.781863174026;4708.199606067045;4790.137775200358;4818.204996175732;4896.877578060435;4921.778519966681;4422.690411671152;4445.266531022345;4449.666275029321;4476.619487343482;4516.864494065183 05:38:31 INFO - PROCESS | 2090 | |4;prototype.html;322.79663811798576;325.22920677993153;327.0336156657079;330.9056918778147;332.459752901535;5181.9740901295445;5336.332958380202;5516.034693928562;5592.832215033555;5649.954758369705;4788;5511.445117898953;5529.972350138244;5532;5540.695261760601;3862.246861861938;4012.217617564576;4108.794560271989;4123.567025462328;4256.404103425523;3297.7468561946425;3605.6394360563863;4220.155968806235;4231.534531201581;4249.766262855542;3842.212346468967;3848.730588858781;4240.925329136382;4243.193793179302;4245.172191422674;3530.6292839252023;3904.7657140571655;3982.5420076691116;4131.843083936473;4249.235137675218;3541.220931395089;3600.837962291703;3604.189057462076;3604.981975090108;3623.0398944279787;5305.628605997582;5326.547243484309;5326.600504962104;5329.680219186861;5333.4399888011585;4580.244259697146;4767.449414563227;4786.282057691339;4812.109759694467;4889.926651100236;5306.92039619406;5802.941970580282;5814.796482123105;5827.921834460596;5833.591648584601;3650.700058962863;3926.9018274543077;3958.6635136013474;3994.940075898864;4018.8191531380867;5493.752781124819;5519.730461993724;6094.908576371314;6117.950486587043;6176.722047507873;4033.757974521538;4051.6353528182603;4053.9391909121387;4062.5734297898293;4064.378475883039;3139.01121146838;3970.722049456568;4029.6977726670616;4136.834526618902;4138.261929989411;4023.2959232134845;4357.477102747691;4464.866054018385;4801.5438533339975;4823.493533179036;4713.505081966344;5495.763453223073;5537.861553461195;5547.972260138674;5907.261592300963;1478.7929689843431;1499.4002399040253;1500.7298686236581;1502.9924850375678;1512.8487151284783;804.63389157933;935.2891802230354;960.8126415348939;982.4596471940404;1001.9499025048718;5105;5513.21582600784;5787.421257874263;5810.680412577349;5913.5564832636865;6030.396960303934;6287.748490060438;6478.384553467413;6514.05546195799;6572.4084832365315;4317.071829556598;5208.166693329049;5533.22534845122;5811.970940145272;5816.510093943684;3058.235441139715;3158.3209609933974;3344.4816053511745;3428.5885693716914;3431.6396778338412;6153.323134455206;6197.566170368031;6244.219472565929;6737.764178253738;6772.2211945625895;5747.683877386784;6088.391160883876;6264.906026409608;6662.333766623396;6704.731810727516;6537.803865884031;6783.864322713617;6797.762078327235;6816.113905192393;6848.589084654837;6417.422431980957;6450.8064758057335;6463.095166676763;6493.902591461227;6531.934680653133;5223.7910483580235;5317.813876514303;5635.323761148688;6419.518536109717;6426.550141490051;6429.807105786834;6685.465162786868;6735.373204450366;6791.286914873867;6797.116374871235;4270.423492828429;4300.591443812833;4306.698531102793;4313.870583882489;4332.393464914851;5433.646812957303;6177.382261773787;6469.28837827848;6469.897283382826;6522.315156908457;5697.829065128053;6254.437100660963;6260.937390626218;6285.654289013965;6303.275123360872;5427.697352635418;6251.3123556407845;6278.183836101461;6279;6279.654618995999;5146.665466744649;6030.758769649165;6101.816945491642;6113.327533971348;6143.877122457615;5897.702505448794;6213.502919766317;6215.160953271251;6248.843778905563;6252.718627661675;5190.480951904779;5377.623566350318;5885.411458854081;5905.907407129695;6193.938060619516 05:38:31 INFO - PROCESS | 2090 | |5;yui.html;655.9803205903823;716.6989864257015;723.5947869193249;736.4734215036248;746.8244962433829;14498.855011449883;14815.037022593524;14875.628109297273;14899.10605363679;14933.925330373346;14949.40202391903;15689.980151290158;15720;15721.13533755643;15776.368945242177;725.3182008911632;906.6962567539882;928.0904713380874;931.7391130483476;937.9671711490099;922.5848368234288;927.2257664849858;927.6057675487924;927.7355953553205;928.6099838067994;976.3360914578084;976.623999760094;976.9853452198223;978.838491648877;981.2983716642599;809.3282575462382;950.0546955779009;961.1541843177959;971.9902800972042;973.990260097397;715.8783006888841;716.3839098375392;718.1418205244735;718.3211864787778;718.7699936020475;11754.882451175465;11833;13260.005499587569;14200;15371;10011.649592264235;12377.133600647958;12414.565490207799;12576.182548134433;12598.874011259953;9927.305088643798;9933.3543319684;10061.748456288578;10168.33905796121;10179.236557258235;3280.343931213743;3332.3241397451907;3362.865485380582;3405.3359594879007;3421.213120982163;58616;79266;80363.39273214503;81031.37937241222;82052.58973705153;9267.76830579241;9397;9440.905590944003;9443.244540436886;9526.571304291185;20294.49263768388;20304;20625.690614640793;20631.896840516;20707.068181931547;7454.180040195574;7535.434842386733;7632.732854350071;7640.617969101522;7706.38348932084;4059.167870586523;4128.938065929014;4134.565870583606;4137.420761093449;4138.937915931264;3602.495650608917;3662.450632405107;3665.157013886821;3670.596234414212;3673.4673472346362;639.6226226526372;647.7050115307344;650.391883588846;656.6355672601752;686.1526015371013;499.91251530981936;501.0603146422098;567.619694804482;594.0851089322396;598.7515181199753;7627.580483073373;7944.086430060502;7982.121966583787;8040.316573091352;8172.591370431454;469.20704998440414;499.96257765137483;537.9704116273564;541.2800974703634;572.4504475703288;3630.3828349180703;3772.7359084863797;3804.933540893324;3816.0459885028745;3825.885223443301;99285.50357248313;100356.49821750844;100897.49551252197;101084.98915010903;101436;76781.23218767741;78634;79369.60315198387;80615.59692201501;81151.59424202841;72439;73219;73370.53258934792;73616.63191684007;75123.62438187774;7793.9471474831635;8088.797780055545;8150.26647601695;8251.216134467217;8577.399582029198;42400.78799605982;44647.77676111599;45459;46172.538274618164;47072.529274706816;58013.259602212;58079;60871.69564152151;62086.689566551875;63803.36196637974;10771.299865508718;10818.837717434402;10848.837267441153;10859.402732849454;11267.605633802777;11821.936025757723;11853.76292474163;11858.52565897389;12003.63989080329;12120.27278363301;10595.364278143335;10743.301685390425;10840.241183117107;10847.186461015614;11627.418629068681;11059.502322395672;11327.263727857662;11335.71660708456;11350.375729334635;11433.656990290305;9853.901460985298;10402.843957340494;10541.36751794864;10606.681799546026;10651.041406273473;9442.622295108393;9572;9769.75575610587;9779.804403912025;9845.360051596623;7185.784426467214;8946.284297256074;9140.177384035203;9454.810903781748;9539.332246742939 05:38:31 INFO - PROCESS | 2090 | __end_tp_report 05:38:31 INFO - PROCESS | 2090 | __start_cc_report 05:38:31 INFO - PROCESS | 2090 | _x_x_mozilla_cycle_collect,420 05:38:31 INFO - PROCESS | 2090 | __end_cc_report 05:38:31 INFO - PROCESS | 2090 | __startTimestamp1462538311024__endTimestamp 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | ------- Summary: start ------- 05:38:31 INFO - PROCESS | 2090 | Number of tests: 6 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | [#0] dojo.html Cycles:165 Average:5786.07 Median:6071.09 stddev:671.95 (11.1%) stddev-sans-first:636.62 05:38:31 INFO - PROCESS | 2090 | Values: 2968.5 2971.9 3447.2 3482.4 3535.4 5774.3 5783.3 5785.8 5814.4 5815.4 5776.1 5817.9 5838.1 5861.9 5880.8 5264.3 5268.8 5338.5 5349.7 5353.5 5180.8 5303.0 5312.8 5402.9 5416.9 5223.5 5261.2 5290.0 5301.7 5313.8 5206.6 5235.5 5334.8 5341.4 5377.3 5237.6 5280.2 5309.4 5318.8 5338.8 5475.0 5590.1 5599.1 5744.9 5829.8 6197.5 6201.0 6248.2 6262.8 6263.1 5275.3 6275.8 6282.1 6282.6 6305.6 5736.6 5787.4 5838.9 5855.5 5945.9 6305.1 6345.2 6359.4 6359.7 6370.0 6192.2 6225.9 6230.5 6232.4 6240.0 5150.5 6152.3 6215.6 6315.7 6335.0 6190.6 6263.9 6267.4 6278.9 6282.0 6288.2 6307.9 6313.4 6325.9 6329.9 4818.7 4966.6 5018.7 5020.6 5064.1 3974.9 4471.0 4513.6 4516.7 4536.8 4651.0 4923.9 5570.9 5735.3 5834.4 6136.4 6191.8 6230.1 6248.9 6323.6 5856.9 5868.2 5892.9 5902.9 5933.0 5332.6 5490.3 5502.1 5505.8 5507.7 6353.4 6428.1 6437.5 6443.6 6452.8 6301.2 6310.7 6313.4 6322.9 6325.9 6254.3 6390.2 6484.6 6515.5 6515.7 6197.3 6262.8 6264.3 6265.4 6298.3 6071.1 6255.7 6268.7 6276.8 6293.1 6059.5 6134.1 6170.2 6199.7 6211.7 6193.5 6200.0 6203.2 6208.8 6217.4 5967.4 6057.7 6087.9 6130.1 6155.8 5932.7 6127.0 6162.3 6204.0 6221.6 5628.2 6079.0 6103.2 6103.6 6108.7 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | [#1] ext.html Cycles:180 Average:68631.76 Median:38976.32 stddev:152013.29 (390.0%) stddev-sans-first:152353.64 05:38:31 INFO - PROCESS | 2090 | Values: 493.9 511.5 582.7 594.1 618.0 38515.0 39576.4 39606.0 39629.6 39780.4 40646.8 40933.8 41521.8 41590.6 41600.4 9410.6 9589.6 9652.0 9658.8 9671.7 9470.2 9692.4 9724.7 9731.4 9782.1 9703.8 9746.7 9763.6 9782.6 9786.4 8282.3 9647.0 9691.5 9717.5 9750.5 9690.5 9791.0 9801.7 9807.9 9834.5 37817.6 39641.8 39853.0 40138.8 40335.4 2998.8 3209.8 3361.4 3361.8 3490.2 28665.9 29235.7 29244.4 29356.0 29456.7 2777.0 2778.9 2788.7 2820.5 2830.3 485441.0 491072.0 492076.5 492659.0 494560.0 32011.9 32267.2 35495.5 66062.0 66602.0 36042.6 36163.3 36330.6 36337.0 36444.8 15947.0 16193.9 16195.4 16202.2 16243.5 13452.4 13517.4 13540.7 13552.1 13588.2 8304.7 8333.5 8394.2 8434.3 8439.5 3436.3 3493.8 3504.9 3506.5 3506.8 935.1 973.3 1021.2 1090.4 1095.1 28422.0 30633.0 30686.8 30792.5 30991.0 1121.5 1142.6 1144.3 1144.6 1175.5 12674.1 15696.6 15909.8 16023.2 16061.4 617757.0 619439.0 625980.0 627372.9 629513.0 60404.0 62309.0 62331.7 62348.8 62590.0 1252.6 1515.9 1517.6 1519.4 1524.4 29506.6 29843.6 29891.0 30513.5 30654.8 54184.0 54481.7 54941.0 55247.0 55510.0 578631.0 580057.0 580819.0 582602.0 582781.0 27702.0 27755.3 27766.0 27965.0 28039.3 48153.5 48409.5 48710.8 48805.3 48913.8 39150.8 39567.4 39713.8 39756.8 40606.4 38395.8 38697.8 38704.0 39147.6 39476.0 35874.6 36702.6 37052.4 37137.3 38805.0 29729.9 31823.7 33175.8 33231.0 33366.3 24804.3 25308.9 25344.2 25526.4 25727.0 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | [#2] jquery.html Cycles:180 Average:33218.30 Median:20468.74 stddev:94404.38 (461.2%) stddev-sans-first:94637.04 05:38:31 INFO - PROCESS | 2090 | Values: 397.2 400.1 401.4 406.4 411.5 11168.9 14881.6 15884.9 20133.8 21391.0 16606.4 19840.5 20277.5 20309.6 20775.4 5726.9 5839.8 6037.6 6067.7 6074.8 5154.9 5664.7 5872.3 5892.7 5992.4 5682.5 5923.3 5962.4 5983.4 5989.7 5085.8 5881.4 5943.7 5949.1 5956.7 5068.1 5119.4 5169.1 5176.5 5186.9 24915.0 25648.8 26055.7 26230.6 26620.7 1948.6 2157.7 2444.5 2458.8 2583.6 9593.6 9661.4 9721.2 9791.2 9969.1 1674.2 1730.1 1734.3 1765.6 1834.5 64118.0 119763.0 121024.6 123479.0 123498.4 11501.5 11877.9 11890.2 11931.0 11935.5 15072.6 15083.2 15091.9 15102.2 15113.9 8771.1 8864.6 8873.8 8945.5 8985.5 7490.6 8595.3 8868.3 8909.5 9003.8 4558.2 4560.2 4648.7 4649.0 4668.0 1678.8 1733.5 1979.8 2007.1 2018.1 816.7 877.5 894.7 1011.5 1013.3 19557.3 19986.3 20191.4 20462.3 20475.2 930.8 934.6 985.9 1027.0 1050.5 10488.8 10799.9 10805.9 10806.5 10811.5 521039.0 521119.0 530328.0 599892.0 641252.0 105828.5 129364.0 132918.0 138783.0 142610.0 1007.6 1027.0 1030.8 1052.0 1057.4 14127.5 17443.7 17618.3 17822.0 17896.1 35241.6 40161.4 42399.8 42696.0 42860.6 56604.7 59892.1 62560.0 72655.6 77080.0 9193.3 10465.4 11668.8 12066.9 12156.2 13031.0 13094.5 13161.9 13171.9 13343.1 9978.4 10399.5 10487.6 11345.1 11376.6 7060.7 8108.8 9479.3 11358.4 11611.5 10581.7 11440.6 11709.5 11916.2 11979.9 12137.1 12176.9 12177.5 12384.8 12441.4 11367.8 11464.3 11579.4 11696.9 11865.6 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | [#3] mootools.html Cycles:180 Average:5655.49 Median:4942.93 stddev:3108.93 (62.9%) stddev-sans-first:3102.71 05:38:31 INFO - PROCESS | 2090 | Values: 1599.9 1618.4 1625.0 1786.5 1821.2 7321.3 7895.1 8458.4 8813.0 8835.7 8450.9 8478.0 8576.2 8577.0 8595.0 2443.3 2456.8 2483.5 2483.9 2487.4 2044.3 2440.2 2475.2 2491.2 2495.7 2466.1 2479.0 2484.7 2485.0 2507.9 2462.7 2463.7 2487.1 2492.5 2495.0 2355.4 2446.1 2458.0 2465.5 2496.3 8614.7 8708.3 8719.4 8724.3 8768.7 5324.9 5448.6 5465.2 5504.2 5538.2 3544.5 3560.8 3580.3 3664.2 3675.7 1029.7 1051.5 1056.6 1069.2 1075.4 11561.1 11607.7 11666.4 11701.3 11714.4 4852.8 4860.1 4979.0 4993.4 5021.8 9915.0 9937.8 9984.2 9997.4 10047.3 5387.4 5410.8 5473.3 5494.3 5523.7 3954.6 4445.5 4928.6 4957.3 5028.5 4103.0 4131.2 4147.6 4185.0 4185.3 1353.8 1381.1 1386.9 1392.6 1396.0 901.3 909.2 920.9 931.8 934.3 5669.9 5741.8 5821.6 5838.1 6069.6 851.2 945.8 961.6 992.7 1008.2 3697.6 3756.0 3761.5 3858.5 3912.7 8636.4 9923.2 10241.5 10389.3 10406.3 10339.5 10441.7 10486.4 10553.3 10632.3 7863.3 8118.0 8160.0 8227.9 8268.9 4752.2 5538.6 5613.1 5786.2 5858.6 9531.1 9753.3 9810.8 9841.5 9915.4 10011.7 10027.7 10157.6 10184.8 10216.9 6405.5 6578.4 7491.8 7549.4 7644.8 8066.0 8135.5 8162.5 8164.2 8165.3 7211.7 7444.7 7450.1 7500.3 7568.1 7333.0 7396.5 7419.0 7485.7 7538.7 6857.2 6919.0 6939.8 6953.3 6960.8 4708.2 4790.1 4818.2 4896.9 4921.8 4422.7 4445.3 4449.7 4476.6 4516.9 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | [#4] prototype.html Cycles:180 Average:4898.00 Median:5463.70 stddev:1579.08 (28.9%) stddev-sans-first:1545.72 05:38:31 INFO - PROCESS | 2090 | Values: 322.8 325.2 327.0 330.9 332.5 5182.0 5336.3 5516.0 5592.8 5650.0 4788.0 5511.4 5530.0 5532.0 5540.7 3862.2 4012.2 4108.8 4123.6 4256.4 3297.7 3605.6 4220.2 4231.5 4249.8 3842.2 3848.7 4240.9 4243.2 4245.2 3530.6 3904.8 3982.5 4131.8 4249.2 3541.2 3600.8 3604.2 3605.0 3623.0 5305.6 5326.5 5326.6 5329.7 5333.4 4580.2 4767.4 4786.3 4812.1 4889.9 5306.9 5802.9 5814.8 5827.9 5833.6 3650.7 3926.9 3958.7 3994.9 4018.8 5493.8 5519.7 6094.9 6118.0 6176.7 4033.8 4051.6 4053.9 4062.6 4064.4 3139.0 3970.7 4029.7 4136.8 4138.3 4023.3 4357.5 4464.9 4801.5 4823.5 4713.5 5495.8 5537.9 5548.0 5907.3 1478.8 1499.4 1500.7 1503.0 1512.8 804.6 935.3 960.8 982.5 1001.9 5105.0 5513.2 5787.4 5810.7 5913.6 6030.4 6287.7 6478.4 6514.1 6572.4 4317.1 5208.2 5533.2 5812.0 5816.5 3058.2 3158.3 3344.5 3428.6 3431.6 6153.3 6197.6 6244.2 6737.8 6772.2 5747.7 6088.4 6264.9 6662.3 6704.7 6537.8 6783.9 6797.8 6816.1 6848.6 6417.4 6450.8 6463.1 6493.9 6531.9 5223.8 5317.8 5635.3 6419.5 6426.6 6429.8 6685.5 6735.4 6791.3 6797.1 4270.4 4300.6 4306.7 4313.9 4332.4 5433.6 6177.4 6469.3 6469.9 6522.3 5697.8 6254.4 6260.9 6285.7 6303.3 5427.7 6251.3 6278.2 6279.0 6279.7 5146.7 6030.8 6101.8 6113.3 6143.9 5897.7 6213.5 6215.2 6248.8 6252.7 5190.5 5377.6 5885.4 5905.9 6193.9 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - PROCESS | 2090 | [#5] yui.html Cycles:180 Average:18073.29 Median:58347.50 stddev:25952.71 (44.5%) stddev-sans-first:25992.56 05:38:31 INFO - PROCESS | 2090 | Values: 656.0 716.7 723.6 736.5 746.8 14498.9 14815.0 14875.6 14899.1 14933.9 14949.4 15690.0 15720.0 15721.1 15776.4 725.3 906.7 928.1 931.7 938.0 922.6 927.2 927.6 927.7 928.6 976.3 976.6 977.0 978.8 981.3 809.3 950.1 961.2 972.0 974.0 715.9 716.4 718.1 718.3 718.8 11754.9 11833.0 13260.0 14200.0 15371.0 10011.6 12377.1 12414.6 12576.2 12598.9 9927.3 9933.4 10061.7 10168.3 10179.2 3280.3 3332.3 3362.9 3405.3 3421.2 58616.0 79266.0 80363.4 81031.4 82052.6 9267.8 9397.0 9440.9 9443.2 9526.6 20294.5 20304.0 20625.7 20631.9 20707.1 7454.2 7535.4 7632.7 7640.6 7706.4 4059.2 4128.9 4134.6 4137.4 4138.9 3602.5 3662.5 3665.2 3670.6 3673.5 639.6 647.7 650.4 656.6 686.2 499.9 501.1 567.6 594.1 598.8 7627.6 7944.1 7982.1 8040.3 8172.6 469.2 500.0 538.0 541.3 572.5 3630.4 3772.7 3804.9 3816.0 3825.9 99285.5 100356.5 100897.5 101085.0 101436.0 76781.2 78634.0 79369.6 80615.6 81151.6 72439.0 73219.0 73370.5 73616.6 75123.6 7793.9 8088.8 8150.3 8251.2 8577.4 42400.8 44647.8 45459.0 46172.5 47072.5 58013.3 58079.0 60871.7 62086.7 63803.4 10771.3 10818.8 10848.8 10859.4 11267.6 11821.9 11853.8 11858.5 12003.6 12120.3 10595.4 10743.3 10840.2 10847.2 11627.4 11059.5 11327.3 11335.7 11350.4 11433.7 9853.9 10402.8 10541.4 10606.7 10651.0 9442.6 9572.0 9769.8 9779.8 9845.4 7185.8 8946.3 9140.2 9454.8 9539.3 05:38:31 INFO - PROCESS | 2090 | -------- Summary: end -------- 05:38:31 INFO - PROCESS | 2090 | 05:38:31 INFO - TEST-INFO | 2090: exit 0 05:38:31 INFO - TEST-OK | dromaeo_css | took 1111007ms 05:38:31 INFO - TEST-START | kraken 05:38:31 INFO - operating with platform_type : mac_ 05:38:31 INFO - Initialising browser for kraken test... 05:38:31 INFO - TEST-INFO | started process 2096 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpdxtP6g/profile http://localhost:49358/getInfo.html) 05:38:34 INFO - PROCESS | 2096 | __metrics Screen width/height:1600/1200 05:38:34 INFO - PROCESS | 2096 | colorDepth:24 05:38:34 INFO - PROCESS | 2096 | Browser inner width/height: 1024/689 05:38:34 INFO - PROCESS | 2096 | __metrics 05:38:34 ERROR - PROCESS | 2096 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:38:34 INFO - TEST-INFO | 2096: exit 0 05:38:34 INFO - Browser initialized. 05:38:34 INFO - Running cycle 1/1 for kraken test... 05:38:34 INFO - TEST-INFO | started process 2099 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/tmpdxtP6g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:47 INFO - PROCESS | 2099 | RSS: Main: 221487104 05:38:47 INFO - PROCESS | 2099 | 05:38:49 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49358/tests/kraken/driver.html?testName=audio-beat-detection) 05:38:50 INFO - PROCESS | 2099 | RSS: Main: 233324544 05:38:50 INFO - PROCESS | 2099 | 05:38:53 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49358/tests/kraken/driver.html?testName=audio-dft) 05:38:54 INFO - PROCESS | 2099 | RSS: Main: 242835456 05:38:54 INFO - PROCESS | 2099 | 05:38:57 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49358/tests/kraken/driver.html?testName=audio-fft) 05:38:58 INFO - PROCESS | 2099 | RSS: Main: 246456320 05:38:58 INFO - PROCESS | 2099 | 05:39:00 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49358/tests/kraken/driver.html?testName=audio-oscillator) 05:39:00 INFO - PROCESS | 2099 | RSS: Main: 246517760 05:39:00 INFO - PROCESS | 2099 | 05:39:03 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49358/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:39:03 INFO - PROCESS | 2099 | RSS: Main: 248446976 05:39:03 INFO - PROCESS | 2099 | 05:39:06 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49358/tests/kraken/driver.html?testName=imaging-darkroom) 05:39:07 INFO - PROCESS | 2099 | RSS: Main: 248098816 05:39:07 INFO - PROCESS | 2099 | 05:39:10 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49358/tests/kraken/driver.html?testName=imaging-desaturate) 05:39:11 INFO - PROCESS | 2099 | RSS: Main: 248258560 05:39:11 INFO - PROCESS | 2099 | 05:39:13 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49358/tests/kraken/driver.html?testName=json-parse-financial) 05:39:14 INFO - PROCESS | 2099 | RSS: Main: 248406016 05:39:14 INFO - PROCESS | 2099 | 05:39:16 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49358/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:39:17 INFO - PROCESS | 2099 | RSS: Main: 248033280 05:39:17 INFO - PROCESS | 2099 | 05:39:18 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:39:19 INFO - PROCESS | 2099 | RSS: Main: 248025088 05:39:19 INFO - PROCESS | 2099 | 05:39:21 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:39:22 INFO - PROCESS | 2099 | RSS: Main: 248102912 05:39:22 INFO - PROCESS | 2099 | 05:39:25 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:39:26 INFO - PROCESS | 2099 | RSS: Main: 248127488 05:39:26 INFO - PROCESS | 2099 | 05:39:28 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:39:29 INFO - PROCESS | 2099 | RSS: Main: 248209408 05:39:29 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49358/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49358/tests/kraken/driver.html?testName=ai-astar) 05:39:31 INFO - PROCESS | 2099 | RSS: Main: 249909248 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | __start_tp_report 05:39:31 INFO - PROCESS | 2099 | _x_x_mozilla_page_load 05:39:31 INFO - PROCESS | 2099 | _x_x_mozilla_page_load_details 05:39:31 INFO - PROCESS | 2099 | |i|pagename|runs| 05:39:31 INFO - PROCESS | 2099 | |0;ai-astar;112;117;106;93;119;147;98;100;148;101 05:39:31 INFO - PROCESS | 2099 | |1;audio-beat-detection;192;161;175;161;141;162;117;208;159;125 05:39:31 INFO - PROCESS | 2099 | |2;audio-dft;276;258;199;194;192;196;200;196;196;198 05:39:31 INFO - PROCESS | 2099 | |3;audio-fft;65;67;79;168;85;48;56;76;151;83 05:39:31 INFO - PROCESS | 2099 | |4;audio-oscillator;80;88;132;80;79;89;121;82;80;123 05:39:31 INFO - PROCESS | 2099 | |5;imaging-gaussian-blur;91;88;91;89;92;112;135;79;136;133 05:39:31 INFO - PROCESS | 2099 | |6;imaging-darkroom;153;151;117;149;104;153;153;97;97;97 05:39:31 INFO - PROCESS | 2099 | |7;imaging-desaturate;90;92;93;92;93;90;90;96;91;90 05:39:31 INFO - PROCESS | 2099 | |8;json-parse-financial;64;75;91;103;101;103;92;81;65;63 05:39:31 INFO - PROCESS | 2099 | |9;json-stringify-tinderbox;65;65;66;73;78;74;68;68;69;69 05:39:31 INFO - PROCESS | 2099 | |10;stanford-crypto-aes;82;82;110;108;107;84;86;81;97;115 05:39:31 INFO - PROCESS | 2099 | |11;stanford-crypto-ccm;148;129;165;155;138;134;142;159;193;168 05:39:31 INFO - PROCESS | 2099 | |12;stanford-crypto-pbkdf2;170;171;173;170;168;167;167;171;169;165 05:39:31 INFO - PROCESS | 2099 | |13;stanford-crypto-sha256-iterative;73;70;70;85;100;96;98;90;65;59 05:39:31 INFO - PROCESS | 2099 | __end_tp_report 05:39:31 INFO - PROCESS | 2099 | __start_cc_report 05:39:31 INFO - PROCESS | 2099 | _x_x_mozilla_cycle_collect,1136 05:39:31 INFO - PROCESS | 2099 | __end_cc_report 05:39:31 INFO - PROCESS | 2099 | __startTimestamp1462538371722__endTimestamp 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | ------- Summary: start ------- 05:39:31 INFO - PROCESS | 2099 | Number of tests: 14 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#0] ai-astar Cycles:10 Average:114.10 Median:133.00 stddev:19.46 (14.6%) stddev-sans-first:20.63 05:39:31 INFO - PROCESS | 2099 | Values: 112.0 117.0 106.0 93.0 119.0 147.0 98.0 100.0 148.0 101.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#1] audio-beat-detection Cycles:10 Average:160.10 Median:161.50 stddev:27.88 (17.3%) stddev-sans-first:27.07 05:39:31 INFO - PROCESS | 2099 | Values: 192.0 161.0 175.0 161.0 141.0 162.0 117.0 208.0 159.0 125.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#2] audio-dft Cycles:10 Average:210.50 Median:198.50 stddev:30.17 (15.2%) stddev-sans-first:20.69 05:39:31 INFO - PROCESS | 2099 | Values: 276.0 258.0 199.0 194.0 192.0 196.0 200.0 196.0 196.0 198.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#3] audio-fft Cycles:10 Average:87.80 Median:71.50 stddev:39.75 (55.6%) stddev-sans-first:41.30 05:39:31 INFO - PROCESS | 2099 | Values: 65.0 67.0 79.0 168.0 85.0 48.0 56.0 76.0 151.0 83.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:80.00 stddev:21.11 (26.4%) stddev-sans-first:21.65 05:39:31 INFO - PROCESS | 2099 | Values: 80.0 88.0 132.0 80.0 79.0 89.0 121.0 82.0 80.0 123.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:88.50 stddev:22.30 (25.2%) stddev-sans-first:23.10 05:39:31 INFO - PROCESS | 2099 | Values: 91.0 88.0 91.0 89.0 92.0 112.0 135.0 79.0 136.0 133.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#6] imaging-darkroom Cycles:10 Average:127.10 Median:153.00 stddev:26.70 (17.5%) stddev-sans-first:26.63 05:39:31 INFO - PROCESS | 2099 | Values: 153.0 151.0 117.0 149.0 104.0 153.0 153.0 97.0 97.0 97.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:1.95 (2.1%) stddev-sans-first:1.96 05:39:31 INFO - PROCESS | 2099 | Values: 90.0 92.0 93.0 92.0 93.0 90.0 90.0 96.0 91.0 90.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#8] json-parse-financial Cycles:10 Average:83.80 Median:70.00 stddev:16.38 (23.4%) stddev-sans-first:15.73 05:39:31 INFO - PROCESS | 2099 | Values: 64.0 75.0 91.0 103.0 101.0 103.0 92.0 81.0 65.0 63.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.00 stddev:4.25 (6.2%) stddev-sans-first:4.18 05:39:31 INFO - PROCESS | 2099 | Values: 65.0 65.0 66.0 73.0 78.0 74.0 68.0 68.0 69.0 69.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:82.00 stddev:13.65 (16.6%) stddev-sans-first:13.62 05:39:31 INFO - PROCESS | 2099 | Values: 82.0 82.0 110.0 108.0 107.0 84.0 86.0 81.0 97.0 115.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#11] stanford-crypto-ccm Cycles:10 Average:153.10 Median:157.00 stddev:19.20 (12.2%) stddev-sans-first:20.27 05:39:31 INFO - PROCESS | 2099 | Values: 148.0 129.0 165.0 155.0 138.0 134.0 142.0 159.0 193.0 168.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:170.00 stddev:2.38 (1.4%) stddev-sans-first:2.50 05:39:31 INFO - PROCESS | 2099 | Values: 170.0 171.0 173.0 170.0 168.0 167.0 167.0 171.0 169.0 165.0 05:39:31 INFO - PROCESS | 2099 | 05:39:31 INFO - PROCESS | 2099 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.60 Median:79.00 stddev:14.97 (18.9%) stddev-sans-first:15.62 05:39:31 INFO - PROCESS | 2099 | Values: 73.0 70.0 70.0 85.0 100.0 96.0 98.0 90.0 65.0 59.0 05:39:31 INFO - PROCESS | 2099 | -------- Summary: end -------- 05:39:31 INFO - PROCESS | 2099 | 05:39:32 INFO - TEST-INFO | 2099: exit 0 05:39:32 INFO - TEST-OK | kraken | took 61123ms 05:39:32 INFO - SUITE-END | took 1172s 05:39:32 INFO - Completed test suite (00:19:32) 05:39:32 INFO - javascript benchmark 05:39:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2968.4953557895155, 2971.9405611887755, 3447.1554469155053, 3482.3557641836246, 3535.434330507118, 5774.335951365594, 5783.334916484606, 5785.7685692572295, 5814.360420353763, 5815.360310365856, 5776.075827867542, 5817.912731309023, 5838.065909454488, 5861.912071318934, 5880.764769409229, 5264.315638966938, 5268.841934741964, 5338.466153384669, 5349.652272602269, 5353.491418315254, 5180.844574662748, 5302.973485132569, 5312.814051508198, 5402.864928376803, 5416.91874621881, 5223.503767142134, 5261.158214685651, 5290.0, 5301.655392399501, 5313.814016509423, 5206.609504287194, 5235.528802407764, 5334.786608535654, 5341.439148889388, 5377.3009508764, 5237.580993520509, 5280.2079688046715, 5309.4425085365865, 5318.813841515567, 5338.813141540028, 5474.972625136849, 5590.105583106683, 5599.1041433370465, 5744.913826292567, 5829.8251052468495, 6197.473214776749, 6200.968995154995, 6248.1564988726395, 6262.812115636492, 6263.060540918898, 5275.261463395128, 6275.780347687854, 6282.120503129566, 6282.623042617412, 6305.59013664115, 5736.598438109292, 5787.39232380594, 5838.9124163137585, 5855.502282306052, 5945.851353716149, 6305.085762564402, 6345.206849143857, 6359.395857393541, 6359.682015899187, 6370.0, 6192.164057852222, 6225.875482490324, 6230.470410015108, 6232.439080482778, 6240.0, 5150.510701483428, 6152.323244443108, 6215.564910456315, 6315.684215789192, 6334.968325158345, 6190.628562286277, 6263.906041409383, 6267.373262673696, 6278.937210627927, 6282.0, 6288.151099601587, 6307.936920630735, 6313.3686631336495, 6325.873482530324, 6329.92391293472, 4818.71087734737, 4966.577840883493, 5018.723970181602, 5020.623453241022, 5064.139096353555, 3974.926769772203, 4470.977645111753, 4513.593776560126, 4516.706414083074, 4536.841210557614, 4651.0, 4923.876903077452, 5570.916436253459, 5735.340435849931, 5834.387389324144, 6136.3965394158, 6191.783287585003, 6230.127782110508, 6248.937510624926, 6323.557350985387, 5856.912146317809, 5868.207791948118, 5892.852678682982, 5902.852428689232, 5932.970335148297, 5332.6000549958135, 5490.313710786152, 5502.064649009598, 5505.752241149158, 5507.697076660822, 6353.364663533702, 6428.132202152743, 6437.452816510649, 6443.613383197023, 6452.806415807533, 6301.212348456443, 6310.652914089769, 6313.43179113882, 6322.905156422566, 6325.873482530232, 6254.312025677263, 6390.233172019387, 6484.610923344614, 6515.54391192612, 6515.73937042513, 6197.25632924052, 6262.812115636538, 6264.34224406449, 6265.4361107500545, 6298.338674439118, 6071.089336599545, 6255.749770009149, 6268.7492500301305, 6276.8430789228805, 6293.118963345044, 6059.545534084873, 6134.130457564626, 6170.167027451416, 6199.690015499117, 6211.65835879031, 6193.535484838565, 6200.038993955944, 6203.193584833943, 6208.751649934133, 6217.409346112112, 5967.401687629407, 6057.697115144137, 6087.8782424350375, 6130.141780150693, 6155.784547540815, 5932.733027013881, 6127.0, 6162.291336496361, 6203.968980155251, 6221.595596286227, 5628.183913332626, 6079.0, 6103.169049285286, 6103.585023804053, 6108.72510737009], "value": 5741.634830772307, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.935788347515, 511.5319482673354, 582.7261187242, 594.0584174084076, 618.0266080922545, 38515.0, 39576.40635390471, 39606.0, 39629.60370396295, 39780.403293950614, 40646.79676601613, 40933.795331023306, 41521.75434736963, 41590.58409415905, 41600.37599436011, 9410.576524056416, 9589.568469418893, 9652.0, 9658.806823863519, 9671.70984870455, 9470.195033422167, 9692.41845489273, 9724.70825875225, 9731.416115033086, 9782.119609235167, 9703.805923881519, 9746.707598772047, 9763.609455621767, 9782.608695652201, 9786.363886347364, 8282.337413006944, 9647.0, 9691.466969316685, 9717.514124293792, 9750.512474376288, 9690.51547422626, 9791.0, 9801.656942006995, 9807.90192098077, 9834.459104749307, 37817.62182378169, 39641.80179099115, 39853.0, 40138.79930600328, 40335.39496907519, 2998.7900846940724, 3209.7753157279, 3361.3613413451367, 3361.781484203519, 3490.232148927257, 28665.856670716512, 29235.707642923724, 29244.41511169786, 29356.0, 29456.705432945826, 2776.986115069432, 2778.9305267368277, 2788.6793018802896, 2820.478211530853, 2830.3348713052624, 485441.0, 491072.0, 492076.5396173032, 492658.9999999964, 494560.0, 32011.87958421444, 32267.193320167185, 35495.4675679865, 66062.0, 66602.0, 36042.63957360393, 36163.276734465166, 36330.636693633256, 36337.0, 36444.817775911484, 15947.0, 16193.919030404773, 16195.43315983935, 16202.189890505428, 16243.51269461918, 13452.394642241123, 13517.391717372742, 13540.661483462794, 13552.051356405154, 13588.184708917497, 8304.667813287522, 8333.499990000619, 8394.24451799341, 8434.325253979667, 8439.451435656663, 3436.295559410315, 3493.7554371193773, 3504.8773292935134, 3506.4915587239707, 3506.8246587670515, 935.1069728409353, 973.3089506450358, 1021.1779517488493, 1090.4438736244572, 1095.1403148528527, 28422.0, 30633.0, 30686.846565767028, 30792.50598955348, 30991.0, 1121.5401685309148, 1142.5886680794906, 1144.2905398652888, 1144.5650652751901, 1175.4534141624015, 12674.112812103249, 15696.607584810472, 15909.840901590836, 16023.19884005795, 16061.35218552291, 617757.0, 619439.0, 625980.0, 627372.8631356906, 629513.0, 60404.0, 62309.0, 62331.688341558, 62348.75302494015, 62590.0, 1252.5866464066837, 1515.9393624254906, 1517.6281810956166, 1519.4226194046191, 1524.3826250368618, 29506.55740163856, 29843.552346714383, 29891.0, 30513.54229686601, 30654.773809047387, 54184.0, 54481.72759136179, 54941.0, 55247.0, 55510.0, 578631.0, 580057.0, 580819.0, 582602.0, 582781.0, 27702.0, 27755.306117347227, 27766.0, 27965.021224257052, 28039.29901752391, 48153.5184648149, 48409.515904840504, 48710.75644621754, 48805.267920981925, 48913.75543122261, 39150.80424597859, 39567.40648890211, 39713.80143099266, 39756.801215993735, 40606.39090413587, 38395.84812455631, 38697.80651096727, 38704.0, 39147.608523914394, 39476.0, 35874.641253588175, 36702.63297367099, 37052.44421333628, 37137.25725485529, 38805.02987425224, 29729.85135074311, 31823.68176318207, 33175.83412082924, 33231.0, 33366.33267334591, 24804.255872323858, 25308.873455632605, 25344.239672809847, 25526.361840954123, 25727.0], "value": 18843.31680317942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.1837873170635, 400.095783529224, 401.39589917174646, 406.4005591752164, 411.4835880969383, 11168.933747256764, 14881.553553393389, 15884.920575397136, 20133.798662013374, 21391.0, 16606.418775342867, 19840.503987400323, 20277.493062673442, 20309.593808123827, 20775.37673869786, 5726.942730572703, 5839.824805255838, 6037.6075555089, 6067.696615169224, 6074.848128796772, 5154.943236636481, 5664.716764161796, 5872.324682661484, 5892.705364731768, 5992.43071908168, 5682.51698605619, 5923.348431672513, 5962.433568810956, 5983.371745966676, 5989.730462129193, 5085.84742457725, 5881.441263080043, 5943.732532036091, 5949.077892926603, 5956.731947062371, 5068.1329189576945, 5119.360079990001, 5169.147090730025, 5176.50823171798, 5186.948130518685, 24915.0, 25648.845801938773, 26055.73944260552, 26230.606540901903, 26620.73379266202, 1948.5580670304014, 2157.7194964654495, 2444.4744379958393, 2458.840175388594, 2583.5866261398273, 9593.616255349847, 9661.420314781066, 9721.17370023549, 9791.167750741195, 9969.052939970692, 1674.1963857348542, 1730.134932533733, 1734.2802736864187, 1765.6380442009358, 1834.5230240137528, 64118.0, 119763.0, 121024.57950840934, 123479.0, 123498.38250808779, 11501.53993840237, 11877.940610297066, 11890.167688261912, 11931.0, 11935.462904169333, 15072.623184420478, 15083.170425626477, 15091.924540377227, 15102.244887755787, 15113.924430377778, 8771.079036701181, 8864.556772161366, 8873.822523549492, 8945.46327220369, 8985.46087234768, 7490.576790409805, 8595.269402100766, 8868.33487488441, 8909.465432074096, 9003.77490562728, 4558.1795276849825, 4560.201964656172, 4648.6745927785405, 4648.953510464852, 4668.0, 1678.7985441747069, 1733.479956013191, 1979.7822239553925, 2007.1469625409143, 2018.1120307064843, 816.6692489541745, 877.4559772940818, 894.673444192865, 1011.5296387179997, 1013.3463915774431, 19557.31549395764, 19986.300479483147, 20191.394258172277, 20462.29367245542, 20475.18099276042, 930.8231436027133, 934.56075644447, 985.940843549375, 1026.9884164098357, 1050.4537640426936, 10488.790224195473, 10799.920007999137, 10805.919408059288, 10806.51370688321, 10811.459427028618, 521039.0, 521119.0, 530328.0, 599892.0, 641252.0, 105828.47085764675, 129364.0, 132918.0, 138783.0, 142610.0, 1007.6171054999201, 1026.984595231072, 1030.8247597908373, 1051.9789604207872, 1057.3655806516183, 14127.505537306144, 17443.738343925106, 17618.29526818913, 17822.019788911733, 17896.10519473995, 35241.64758352384, 40161.39757903575, 42399.788001059795, 42696.0, 42860.6426646525, 56604.71697641486, 59892.10161847663, 62560.0, 72655.63672181606, 77080.0, 9193.310501712265, 10465.372077675363, 11668.766624667289, 12066.879331206575, 12156.209846359958, 13031.0, 13094.54169104115, 13161.868381316448, 13171.868281317447, 13343.132696374705, 9978.351407158512, 10399.480025998519, 10487.632932847313, 11345.149113816662, 11376.601818936297, 7060.717571297296, 8108.75673729789, 9479.336446448682, 11358.375289359165, 11611.477483513094, 10581.735456613646, 11440.599579014695, 11709.531618735155, 11916.225445346025, 11979.940100299442, 12137.089718271342, 12176.939115304367, 12177.512899484276, 12384.75230495367, 12441.377931103227, 11367.772644546898, 11464.31214127155, 11579.363135027654, 11696.88303116958, 11865.644030679094], "value": 9526.165293153972, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1599.8800089993254, 1618.4254589620684, 1625.016864796798, 1786.5087101047218, 1821.1531637788426, 7321.304476074771, 7895.131535531087, 8458.407911446202, 8812.955935220323, 8835.734927952164, 8450.87323690145, 8478.025027121883, 8576.185262400062, 8577.013643431008, 8594.95702521488, 2443.2670198940336, 2456.7666071723156, 2483.4908843687, 2483.863387513686, 2487.4030232744194, 2044.2640649366217, 2440.2313271319567, 2475.220305603738, 2491.1530079772874, 2495.737947515512, 2466.0628960994795, 2478.9876050619723, 2484.7266800651914, 2485.0184177249976, 2507.924762257135, 2462.704475462947, 2463.655088287632, 2487.1046423287603, 2492.538880307149, 2494.9750502494926, 2355.4346956730433, 2446.1316232737477, 2457.987710061438, 2465.506898620283, 2496.3135137837057, 8614.655413783441, 8708.303335733126, 8719.433236839584, 8724.258438032775, 8768.736937891874, 5324.92012619811, 5448.564114870801, 5465.180222966547, 5504.2018907258225, 5538.196961440569, 3544.521489598923, 3560.786352818839, 3580.2839432113683, 3664.2121943782213, 3675.7426980111404, 1029.6962396093095, 1051.5110473629722, 1056.609054649777, 1069.1607088435542, 1075.3601607043797, 11561.075113990944, 11607.709807254801, 11666.416679166008, 11701.297922124615, 11714.414279286002, 4852.757362131879, 4860.149473842063, 4979.0, 4993.400791904995, 5021.84934451967, 9915.008499150028, 9937.80124397508, 9984.151347135576, 9997.400155990665, 10047.296689231684, 5387.380451248078, 5410.810621628224, 5473.28847249855, 5494.2582751328055, 5523.696196709219, 3954.6440820326306, 4445.466544014739, 4928.581070609039, 4957.281194226817, 5028.547430731252, 4102.979485102555, 4131.170148448263, 4147.585241475828, 4185.037441388497, 4185.330347144442, 1353.78339465685, 1381.1367895065584, 1386.8821150202346, 1392.5822253324166, 1396.0, 901.3336766985518, 909.1590278991906, 920.9079092090872, 931.8229536388064, 934.2525979216599, 5669.943300567024, 5741.799037033684, 5821.592488525763, 5838.12428135783, 6069.605475644069, 851.1913682001999, 945.7588314979773, 961.6393852305386, 992.7220378294089, 1008.2438171371472, 3697.5932647408763, 3756.0, 3761.5298087739034, 3858.5369755629504, 3912.7065470089856, 8636.438631488933, 9923.206143508502, 10241.539130739135, 10389.324693895023, 10406.323588966692, 10339.534720937576, 10441.738956526147, 10486.423246721504, 10553.314034587727, 10632.255742098134, 7863.292303692695, 8118.02583689961, 8160.0, 8227.917720822836, 8268.917310826815, 4752.158491390917, 5538.556915446835, 5613.101903695389, 5786.218860453785, 5858.619189752652, 9531.142197202285, 9753.317267791186, 9810.75473113178, 9841.45871977048, 9915.405075695482, 10011.699649010541, 10027.696399468023, 10157.593696252363, 10184.847227291744, 10216.897831021595, 6405.487560995202, 6578.407943285128, 7491.775246742606, 7549.433792465477, 7644.80887977805, 8066.0, 8135.511869287861, 8162.510249384818, 8164.183581641788, 8165.346772258324, 7211.67547460355, 7444.664990075351, 7450.105987281561, 7500.32497075244, 7568.091828980558, 7333.046703928455, 7396.4822462429265, 7418.9629051856555, 7485.737999170003, 7538.698452061856, 6857.245702972768, 6918.965405173143, 6939.826504337432, 6953.26990665993, 6960.781863174026, 4708.199606067045, 4790.137775200358, 4818.204996175732, 4896.877578060435, 4921.778519966681, 4422.690411671152, 4445.266531022345, 4449.666275029321, 4476.619487343482, 4516.864494065183], "value": 4593.840751289683, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.79663811798576, 325.22920677993153, 327.0336156657079, 330.9056918778147, 332.459752901535, 5181.9740901295445, 5336.332958380202, 5516.034693928562, 5592.832215033555, 5649.954758369705, 4788.0, 5511.445117898953, 5529.972350138244, 5532.0, 5540.695261760601, 3862.246861861938, 4012.217617564576, 4108.794560271989, 4123.567025462328, 4256.404103425523, 3297.7468561946425, 3605.6394360563863, 4220.155968806235, 4231.534531201581, 4249.766262855542, 3842.212346468967, 3848.730588858781, 4240.925329136382, 4243.193793179302, 4245.172191422674, 3530.6292839252023, 3904.7657140571655, 3982.5420076691116, 4131.843083936473, 4249.235137675218, 3541.220931395089, 3600.837962291703, 3604.189057462076, 3604.981975090108, 3623.0398944279787, 5305.628605997582, 5326.547243484309, 5326.600504962104, 5329.680219186861, 5333.4399888011585, 4580.244259697146, 4767.449414563227, 4786.282057691339, 4812.109759694467, 4889.926651100236, 5306.92039619406, 5802.941970580282, 5814.796482123105, 5827.921834460596, 5833.591648584601, 3650.700058962863, 3926.9018274543077, 3958.6635136013474, 3994.940075898864, 4018.8191531380867, 5493.752781124819, 5519.730461993724, 6094.908576371314, 6117.950486587043, 6176.722047507873, 4033.757974521538, 4051.6353528182603, 4053.9391909121387, 4062.5734297898293, 4064.378475883039, 3139.01121146838, 3970.722049456568, 4029.6977726670616, 4136.834526618902, 4138.261929989411, 4023.2959232134845, 4357.477102747691, 4464.866054018385, 4801.5438533339975, 4823.493533179036, 4713.505081966344, 5495.763453223073, 5537.861553461195, 5547.972260138674, 5907.261592300963, 1478.7929689843431, 1499.4002399040253, 1500.7298686236581, 1502.9924850375678, 1512.8487151284783, 804.63389157933, 935.2891802230354, 960.8126415348939, 982.4596471940404, 1001.9499025048718, 5105.0, 5513.21582600784, 5787.421257874263, 5810.680412577349, 5913.5564832636865, 6030.396960303934, 6287.748490060438, 6478.384553467413, 6514.05546195799, 6572.4084832365315, 4317.071829556598, 5208.166693329049, 5533.22534845122, 5811.970940145272, 5816.510093943684, 3058.235441139715, 3158.3209609933974, 3344.4816053511745, 3428.5885693716914, 3431.6396778338412, 6153.323134455206, 6197.566170368031, 6244.219472565929, 6737.764178253738, 6772.2211945625895, 5747.683877386784, 6088.391160883876, 6264.906026409608, 6662.333766623396, 6704.731810727516, 6537.803865884031, 6783.864322713617, 6797.762078327235, 6816.113905192393, 6848.589084654837, 6417.422431980957, 6450.8064758057335, 6463.095166676763, 6493.902591461227, 6531.934680653133, 5223.7910483580235, 5317.813876514303, 5635.323761148688, 6419.518536109717, 6426.550141490051, 6429.807105786834, 6685.465162786868, 6735.373204450366, 6791.286914873867, 6797.116374871235, 4270.423492828429, 4300.591443812833, 4306.698531102793, 4313.870583882489, 4332.393464914851, 5433.646812957303, 6177.382261773787, 6469.28837827848, 6469.897283382826, 6522.315156908457, 5697.829065128053, 6254.437100660963, 6260.937390626218, 6285.654289013965, 6303.275123360872, 5427.697352635418, 6251.3123556407845, 6278.183836101461, 6279.0, 6279.654618995999, 5146.665466744649, 6030.758769649165, 6101.816945491642, 6113.327533971348, 6143.877122457615, 5897.702505448794, 6213.502919766317, 6215.160953271251, 6248.843778905563, 6252.718627661675, 5190.480951904779, 5377.623566350318, 5885.411458854081, 5905.907407129695, 6193.938060619516], "value": 4399.836831916377, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.9803205903823, 716.6989864257015, 723.5947869193249, 736.4734215036248, 746.8244962433829, 14498.855011449883, 14815.037022593524, 14875.628109297273, 14899.10605363679, 14933.925330373346, 14949.40202391903, 15689.980151290158, 15720.0, 15721.13533755643, 15776.368945242177, 725.3182008911632, 906.6962567539882, 928.0904713380874, 931.7391130483476, 937.9671711490099, 922.5848368234288, 927.2257664849858, 927.6057675487924, 927.7355953553205, 928.6099838067994, 976.3360914578084, 976.623999760094, 976.9853452198223, 978.838491648877, 981.2983716642599, 809.3282575462382, 950.0546955779009, 961.1541843177959, 971.9902800972042, 973.990260097397, 715.8783006888841, 716.3839098375392, 718.1418205244735, 718.3211864787778, 718.7699936020475, 11754.882451175465, 11833.0, 13260.005499587569, 14200.0, 15371.0, 10011.649592264235, 12377.133600647958, 12414.565490207799, 12576.182548134433, 12598.874011259953, 9927.305088643798, 9933.3543319684, 10061.748456288578, 10168.33905796121, 10179.236557258235, 3280.343931213743, 3332.3241397451907, 3362.865485380582, 3405.3359594879007, 3421.213120982163, 58616.0, 79266.0, 80363.39273214503, 81031.37937241222, 82052.58973705153, 9267.76830579241, 9397.0, 9440.905590944003, 9443.244540436886, 9526.571304291185, 20294.49263768388, 20304.0, 20625.690614640793, 20631.896840516, 20707.068181931547, 7454.180040195574, 7535.434842386733, 7632.732854350071, 7640.617969101522, 7706.38348932084, 4059.167870586523, 4128.938065929014, 4134.565870583606, 4137.420761093449, 4138.937915931264, 3602.495650608917, 3662.450632405107, 3665.157013886821, 3670.596234414212, 3673.4673472346362, 639.6226226526372, 647.7050115307344, 650.391883588846, 656.6355672601752, 686.1526015371013, 499.91251530981936, 501.0603146422098, 567.619694804482, 594.0851089322396, 598.7515181199753, 7627.580483073373, 7944.086430060502, 7982.121966583787, 8040.316573091352, 8172.591370431454, 469.20704998440414, 499.96257765137483, 537.9704116273564, 541.2800974703634, 572.4504475703288, 3630.3828349180703, 3772.7359084863797, 3804.933540893324, 3816.0459885028745, 3825.885223443301, 99285.50357248313, 100356.49821750844, 100897.49551252197, 101084.98915010903, 101436.0, 76781.23218767741, 78634.0, 79369.60315198387, 80615.59692201501, 81151.59424202841, 72439.0, 73219.0, 73370.53258934792, 73616.63191684007, 75123.62438187774, 7793.9471474831635, 8088.797780055545, 8150.26647601695, 8251.216134467217, 8577.399582029198, 42400.78799605982, 44647.77676111599, 45459.0, 46172.538274618164, 47072.529274706816, 58013.259602212, 58079.0, 60871.69564152151, 62086.689566551875, 63803.36196637974, 10771.299865508718, 10818.837717434402, 10848.837267441153, 10859.402732849454, 11267.605633802777, 11821.936025757723, 11853.76292474163, 11858.52565897389, 12003.63989080329, 12120.27278363301, 10595.364278143335, 10743.301685390425, 10840.241183117107, 10847.186461015614, 11627.418629068681, 11059.502322395672, 11327.263727857662, 11335.71660708456, 11350.375729334635, 11433.656990290305, 9853.901460985298, 10402.843957340494, 10541.36751794864, 10606.681799546026, 10651.041406273473, 9442.622295108393, 9572.0, 9769.75575610587, 9779.804403912025, 9845.360051596623, 7185.784426467214, 8946.284297256074, 9140.177384035203, 9454.810903781748, 9539.332246742939], "value": 6786.91748878127, "unit": "score"}], "name": "dromaeo_css", "value": 7217.8208470401405, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 106.0, 93.0, 119.0, 147.0, 98.0, 100.0, 148.0, 101.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 161.0, 175.0, 161.0, 141.0, 162.0, 117.0, 208.0, 159.0, 125.0], "value": 160.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [276.0, 258.0, 199.0, 194.0, 192.0, 196.0, 200.0, 196.0, 196.0, 198.0], "value": 210.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 79.0, 168.0, 85.0, 48.0, 56.0, 76.0, 151.0, 83.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 132.0, 80.0, 79.0, 89.0, 121.0, 82.0, 80.0, 123.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 89.0, 92.0, 112.0, 135.0, 79.0, 136.0, 133.0], "value": 104.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 117.0, 149.0, 104.0, 153.0, 153.0, 97.0, 97.0, 97.0], "value": 127.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 93.0, 92.0, 93.0, 90.0, 90.0, 96.0, 91.0, 90.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 91.0, 103.0, 101.0, 103.0, 92.0, 81.0, 65.0, 63.0], "value": 83.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 73.0, 78.0, 74.0, 68.0, 68.0, 69.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 110.0, 108.0, 107.0, 84.0, 86.0, 81.0, 97.0, 115.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 129.0, 165.0, 155.0, 138.0, 134.0, 142.0, 159.0, 193.0, 168.0], "value": 153.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 173.0, 170.0, 168.0, 167.0, 167.0, 171.0, 169.0, 165.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 70.0, 70.0, 85.0, 100.0, 96.0, 98.0, 90.0, 65.0, 59.0], "value": 80.6, "unit": "score"}], "name": "kraken", "value": 1642.6, "alertThreshold": 2.0}]} 05:39:32 INFO - Return code: 0 05:39:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:39:33 ERROR - # TBPL SUCCESS # 05:39:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:39:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:39:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:39:33 INFO - 2016-05-06 05:39:33.045 screenresolution[2107:14886] starting screenresolution argv=screenresolution get 05:39:33 INFO - 2016-05-06 05:39:33.049 screenresolution[2107:14886] Display 0: 1600x1200x32@60 05:39:33 INFO - 2016-05-06 05:39:33.055 screenresolution[2108:14888] starting screenresolution argv=screenresolution list 05:39:33 INFO - Available Modes on Display 0 05:39:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:39:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:39:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:39:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:39:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:39:33 INFO - Intel Iris: 05:39:33 INFO - Chipset Model: Intel Iris 05:39:33 INFO - Type: GPU 05:39:33 INFO - Bus: Built-In 05:39:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:39:33 INFO - Vendor: Intel (0x8086) 05:39:33 INFO - Device ID: 0x0a2e 05:39:33 INFO - Revision ID: 0x0009 05:39:33 INFO - Displays: 05:39:33 INFO - Display: 05:39:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:39:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:39:33 INFO - Display Serial Number: 1600x1200 60 05:39:33 INFO - Main Display: Yes 05:39:33 INFO - Mirror: Off 05:39:33 INFO - Online: Yes 05:39:33 INFO - Rotation: Supported 05:39:33 INFO - Return code: 0 05:39:33 INFO - Running post-action listener: _resource_record_post_action 05:39:33 INFO - Running post-run listener: _resource_record_post_run 05:39:34 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 30518784; Write bytes: 656542208; Read time: 301; Write time: 1980 05:39:34 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350798336; Write bytes: 346314752; Read time: 27213; Write time: 728 05:39:34 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 30494208; Write bytes: 299438592; Read time: 300; Write time: 1219 05:39:34 INFO - Running post-run listener: _upload_blobber_files 05:39:34 INFO - Blob upload gear active. 05:39:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:39:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:39:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:39:34 INFO - (blobuploader) - INFO - Open directory for files ... 05:39:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:39:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:39:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:35 INFO - (blobuploader) - INFO - Done attempting. 05:39:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:39:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:39:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:39:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:39:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:39:37 INFO - (blobuploader) - INFO - Done attempting. 05:39:37 INFO - (blobuploader) - INFO - Iteration through files over. 05:39:37 INFO - Return code: 0 05:39:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:39:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:39:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25cab79b662c7319f869d269e5e142f97a8dadb8b03e12f3189aa02852c917e6239be3c9e616cd61199acbba62e692fb0f4a915d80d465e6f572337ac1f8c2f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2230a5768532cf6424a8afd2d5ea6daa7ae17c21f46ea268bf6cf912e6511308d38c02847ffacb617e75654d5b21846514ecf3272e0f8d9501cf91dd130ded2"} 05:39:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:39:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:39:37 INFO - Contents: 05:39:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25cab79b662c7319f869d269e5e142f97a8dadb8b03e12f3189aa02852c917e6239be3c9e616cd61199acbba62e692fb0f4a915d80d465e6f572337ac1f8c2f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2230a5768532cf6424a8afd2d5ea6daa7ae17c21f46ea268bf6cf912e6511308d38c02847ffacb617e75654d5b21846514ecf3272e0f8d9501cf91dd130ded2"} 05:39:37 INFO - Running post-run listener: copy_logs_to_upload_dir 05:39:37 INFO - Copying logs to upload dir... 05:39:37 INFO - mkdir: /builds/slave/test/build/upload/logs 05:39:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.715554 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-06 05:39:37.450612) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:37.453709) ========= 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.WmBBG1Boal/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.cSGzUnDWDv/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25cab79b662c7319f869d269e5e142f97a8dadb8b03e12f3189aa02852c917e6239be3c9e616cd61199acbba62e692fb0f4a915d80d465e6f572337ac1f8c2f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2230a5768532cf6424a8afd2d5ea6daa7ae17c21f46ea268bf6cf912e6511308d38c02847ffacb617e75654d5b21846514ecf3272e0f8d9501cf91dd130ded2"} build_url:https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/KgU4R2puSKKcGtaqYiCVWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25cab79b662c7319f869d269e5e142f97a8dadb8b03e12f3189aa02852c917e6239be3c9e616cd61199acbba62e692fb0f4a915d80d465e6f572337ac1f8c2f4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2230a5768532cf6424a8afd2d5ea6daa7ae17c21f46ea268bf6cf912e6511308d38c02847ffacb617e75654d5b21846514ecf3272e0f8d9501cf91dd130ded2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:37.484040) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:37.484387) ========= 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.WmBBG1Boal/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.cSGzUnDWDv/Listeners TMPDIR=/var/folders/k2/1p7n4dw54cbgz4k9crwktl8800000w/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.005177 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 05:39:37.532911) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:39:37.533187) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 05:39:37.536481) ========= ========= Total master_lag: 0.20 =========