builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0011 starttime: 1461072900.27 results: success (0) buildid: 20160419032946 builduid: 888dd407cf58424d8f5448d914a222f8 revision: 29d7a174bdb4f63cb198b639d62a80fc6ea02dc6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.266240) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.267178) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.267862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.288094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.288629) ========= 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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-19 06:35:00-- 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% 60.6M=0s 2016-04-19 06:35:00 (60.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142299 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.451180) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.451927) ========= 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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.020284 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:00.501349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 06:35:00.501691) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 29d7a174bdb4f63cb198b639d62a80fc6ea02dc6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 29d7a174bdb4f63cb198b639d62a80fc6ea02dc6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-19 06:35:00,581 truncating revision to first 12 chars 2016-04-19 06:35:00,581 Setting DEBUG logging. 2016-04-19 06:35:00,581 attempt 1/10 2016-04-19 06:35:00,581 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/29d7a174bdb4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 06:35:01,691 unpacking tar archive at: fx-team-29d7a174bdb4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.353504 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 06:35:01.879001) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:01.879810) ========= script_repo_revision: 29d7a174bdb4f63cb198b639d62a80fc6ea02dc6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:01.880769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:01.881449) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 06:35:01.896893) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-19 06:35:01.897571) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:02 INFO - MultiFileLogger online at 20160419 06:35:02 in /builds/slave/test 06:35:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 06:35:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:35:02 INFO - {'append_to_log': False, 06:35:02 INFO - 'base_work_dir': '/builds/slave/test', 06:35:02 INFO - 'blob_upload_branch': 'Fx-Team', 06:35:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:35:02 INFO - 'branch': 'Fx-Team', 06:35:02 INFO - 'buildbot_json_path': 'buildprops.json', 06:35:02 INFO - 'config_files': ('talos/mac_config.py',), 06:35:02 INFO - 'default_actions': ('clobber', 06:35:02 INFO - 'read-buildbot-config', 06:35:02 INFO - 'download-and-extract', 06:35:02 INFO - 'populate-webroot', 06:35:02 INFO - 'create-virtualenv', 06:35:02 INFO - 'install', 06:35:02 INFO - 'run-tests'), 06:35:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:35:02 INFO - 'download_minidump_stackwalk': True, 06:35:02 INFO - 'download_symbols': 'ondemand', 06:35:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:35:02 INFO - 'tooltool.py': '/tools/tooltool.py', 06:35:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:35:02 INFO - '/tools/misc-python/virtualenv.py')}, 06:35:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:35:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:35:02 INFO - 'installer_path': 'installer.exe', 06:35:02 INFO - 'log_level': 'info', 06:35:02 INFO - 'log_name': 'talos', 06:35:02 INFO - 'log_to_console': True, 06:35:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:35:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:35:02 INFO - 'opt_config_files': (), 06:35:02 INFO - 'pip_index': False, 06:35:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:35:02 INFO - 'cmd': ('bash', 06:35:02 INFO - '-c', 06:35:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:35:02 INFO - 'enabled': True, 06:35:02 INFO - 'halt_on_failure': False, 06:35:02 INFO - 'name': 'check_screen_resolution'},), 06:35:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:35:02 INFO - 'cmd': ('bash', 06:35:02 INFO - '-c', 06:35:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:35:02 INFO - 'enabled': True, 06:35:02 INFO - 'halt_on_failure': False, 06:35:02 INFO - 'name': 'check_screen_resolution'},), 06:35:02 INFO - 'run_cmd_checks_enabled': True, 06:35:02 INFO - 'sps_profile': False, 06:35:02 INFO - 'sps_profile_interval': 0, 06:35:02 INFO - 'suite': 'dromaeojs', 06:35:02 INFO - 'system_bits': '32', 06:35:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:35:02 INFO - 'title': 't-yosemite-r7-0011', 06:35:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:35:02 INFO - 'use_talos_json': True, 06:35:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:35:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:35:02 INFO - 'work_dir': 'build'} 06:35:02 INFO - ##### 06:35:02 INFO - ##### Running clobber step. 06:35:02 INFO - ##### 06:35:02 INFO - Running pre-action listener: _resource_record_pre_action 06:35:02 INFO - Running main action method: clobber 06:35:02 INFO - rmtree: /builds/slave/test/build 06:35:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:35:02 INFO - Running post-action listener: _resource_record_post_action 06:35:02 INFO - ##### 06:35:02 INFO - ##### Running read-buildbot-config step. 06:35:02 INFO - ##### 06:35:02 INFO - Running pre-action listener: _resource_record_pre_action 06:35:02 INFO - Running main action method: read_buildbot_config 06:35:02 INFO - Using buildbot properties: 06:35:02 INFO - { 06:35:02 INFO - "project": "", 06:35:02 INFO - "product": "firefox", 06:35:02 INFO - "script_repo_revision": "production", 06:35:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:35:02 INFO - "repository": "", 06:35:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:35:02 INFO - "buildid": "20160419032946", 06:35:02 INFO - "pgo_build": "False", 06:35:02 INFO - "basedir": "/builds/slave/test", 06:35:02 INFO - "buildnumber": 676, 06:35:02 INFO - "slavename": "t-yosemite-r7-0011", 06:35:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:35:02 INFO - "platform": "yosemite_r7", 06:35:02 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:35:02 INFO - "branch": "fx-team", 06:35:02 INFO - "repo_path": "integration/fx-team", 06:35:02 INFO - "revision": "29d7a174bdb4f63cb198b639d62a80fc6ea02dc6", 06:35:02 INFO - "stage_platform": "macosx64", 06:35:02 INFO - "builduid": "888dd407cf58424d8f5448d914a222f8", 06:35:02 INFO - "slavebuilddir": "test" 06:35:02 INFO - } 06:35:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 06:35:02 INFO - Running post-action listener: _resource_record_post_action 06:35:02 INFO - ##### 06:35:02 INFO - ##### Running download-and-extract step. 06:35:02 INFO - ##### 06:35:02 INFO - Running pre-action listener: _resource_record_pre_action 06:35:02 INFO - Running main action method: download_and_extract 06:35:02 INFO - mkdir: /builds/slave/test/build/tests 06:35:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:02 INFO - https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:35:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 06:35:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 06:35:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 06:35:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 06:35:03 INFO - Downloaded 1199 bytes. 06:35:03 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 06:35:03 INFO - Using the following test package requirements: 06:35:03 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 06:35:03 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 06:35:03 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 06:35:03 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'jsshell-mac.zip'], 06:35:03 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 06:35:03 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 06:35:03 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 06:35:03 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 06:35:03 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 06:35:03 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 06:35:03 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 06:35:03 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:35: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')]} 06:35:03 INFO - https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:35:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:35:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:35:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:35:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:35:04 INFO - Downloaded 18039402 bytes. 06:35:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:35:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:35:07 INFO - Return code: 0 06:35:07 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:35:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:07 INFO - https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:35:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:35:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:35:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 06:35:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:35:08 INFO - Downloaded 18039402 bytes. 06:35:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:35:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:35:12 INFO - Return code: 0 06:35:12 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')]} 06:35:12 INFO - https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:35:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 06:35:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 06:35:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 06:35:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:35:13 INFO - Downloaded 10845952 bytes. 06:35:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:35:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:35:13 INFO - Return code: 0 06:35: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')]} 06:35:13 INFO - https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:35:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:35:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:35:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 06:35:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 06:35:16 INFO - Downloaded 109953508 bytes. 06:35:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:35:16 INFO - mkdir: /builds/slave/test/properties 06:35:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:35:16 INFO - Writing to file /builds/slave/test/properties/build_url 06:35:16 INFO - Contents: 06:35:16 INFO - build_url:https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 06:35:18 INFO - Running post-action listener: _resource_record_post_action 06:35:18 INFO - Running post-action listener: set_extra_try_arguments 06:35:18 INFO - ##### 06:35:18 INFO - ##### Running populate-webroot step. 06:35:18 INFO - ##### 06:35:18 INFO - Running pre-action listener: _resource_record_pre_action 06:35:18 INFO - Running main action method: populate_webroot 06:35:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:35:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:35:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:35:18 INFO - '--tppagecycles', 06:35:18 INFO - '7'], 06:35:18 INFO - 'tests': ['tsvgm']}}, 06:35:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:35:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:35:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:35:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:35:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:35:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:35:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['damp', 'tps']}, 06:35:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'tests': ['damp', 'tps']}, 06:35:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['dromaeo_dom']}, 06:35:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:35:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['a11yr', 06:35:18 INFO - 'ts_paint', 06:35:18 INFO - 'tpaint', 06:35:18 INFO - 'sessionrestore', 06:35:18 INFO - 'sessionrestore_no_auto_restore', 06:35:18 INFO - 'tabpaint']}, 06:35:18 INFO - 'other-e10s': {'tests': ['a11yr', 06:35:18 INFO - 'ts_paint', 06:35:18 INFO - 'tpaint', 06:35:18 INFO - 'sessionrestore', 06:35:18 INFO - 'sessionrestore_no_auto_restore', 06:35:18 INFO - 'tabpaint']}, 06:35:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:35:18 INFO - 'ts_paint', 06:35:18 INFO - 'tpaint', 06:35:18 INFO - 'sessionrestore', 06:35:18 INFO - 'sessionrestore_no_auto_restore', 06:35:18 INFO - 'tabpaint']}, 06:35:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:35:18 INFO - 'ts_paint', 06:35:18 INFO - 'tpaint', 06:35:18 INFO - 'sessionrestore', 06:35:18 INFO - 'sessionrestore_no_auto_restore', 06:35:18 INFO - 'tabpaint']}, 06:35:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['a11yr', 06:35:18 INFO - 'ts_paint', 06:35:18 INFO - 'tpaint', 06:35:18 INFO - 'sessionrestore', 06:35:18 INFO - 'sessionrestore_no_auto_restore', 06:35:18 INFO - 'tabpaint']}, 06:35:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['a11yr', 06:35:18 INFO - 'ts_paint', 06:35:18 INFO - 'tpaint', 06:35:18 INFO - 'sessionrestore', 06:35:18 INFO - 'sessionrestore_no_auto_restore', 06:35:18 INFO - 'tabpaint']}, 06:35:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['tsvgx', 06:35:18 INFO - 'tsvgr_opacity', 06:35:18 INFO - 'tart', 06:35:18 INFO - 'tscrollx', 06:35:18 INFO - 'cart']}, 06:35:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:35:18 INFO - 'tsvgr_opacity', 06:35:18 INFO - 'tart', 06:35:18 INFO - 'tscrollx', 06:35:18 INFO - 'cart']}, 06:35:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'talos_options': ['--disable-e10s'], 06:35:18 INFO - 'tests': ['tp5o']}, 06:35:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'tests': ['tp5o']}, 06:35:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'talos_options': ['--disable-e10s', 06:35:18 INFO - '--xperf_path', 06:35:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:35:18 INFO - 'tests': ['tp5n']}, 06:35:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:35:18 INFO - 'talos_options': ['--xperf_path', 06:35:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:35:18 INFO - 'tests': ['tp5n']}}, 06:35:18 INFO - 'talos.zip': {'path': '', 06:35:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:35:18 INFO - Running post-action listener: _resource_record_post_action 06:35:18 INFO - ##### 06:35:18 INFO - ##### Running create-virtualenv step. 06:35:18 INFO - ##### 06:35:18 INFO - Running pre-action listener: _resource_record_pre_action 06:35:18 INFO - Running main action method: create_virtualenv 06:35:18 INFO - Creating virtualenv /builds/slave/test/build/venv 06:35:18 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 06:35:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:35:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:35:18 INFO - Using real prefix '/tools/python27' 06:35:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:35:19 INFO - Installing distribute.............................................................................................................................................................................................done. 06:35:22 INFO - Installing pip.................done. 06:35:22 INFO - Return code: 0 06:35:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:35: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')]} 06:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35: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')]} 06:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35: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', 'pip>=1.5'] in /builds/slave/test/build 06:35: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 pip>=1.5 06:35:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:22 INFO - 'HOME': '/Users/cltbld', 06:35:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:22 INFO - 'LOGNAME': 'cltbld', 06:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:22 INFO - 'MOZ_NO_REMOTE': '1', 06:35:22 INFO - 'NO_EM_RESTART': '1', 06:35:22 INFO - 'PAGER': '/bin/cat', 06:35:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:22 INFO - 'PWD': '/builds/slave/test', 06:35:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:22 INFO - 'SHELL': '/bin/bash', 06:35:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:22 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:22 INFO - 'USER': 'cltbld', 06:35:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:22 INFO - 'XPC_FLAGS': '0x0', 06:35:22 INFO - 'XPC_SERVICE_NAME': '0', 06:35:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:22 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 06:35:22 INFO - Cleaning up... 06:35:22 INFO - Return code: 0 06:35:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:35: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')]} 06:35:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35: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')]} 06:35:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35: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', '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 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35: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', 'psutil>=0.7.1'] in /builds/slave/test/build 06:35: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 psutil>=0.7.1 06:35:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:22 INFO - 'HOME': '/Users/cltbld', 06:35:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:22 INFO - 'LOGNAME': 'cltbld', 06:35:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:22 INFO - 'MOZ_NO_REMOTE': '1', 06:35:22 INFO - 'NO_EM_RESTART': '1', 06:35:22 INFO - 'PAGER': '/bin/cat', 06:35:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:22 INFO - 'PWD': '/builds/slave/test', 06:35:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:22 INFO - 'SHELL': '/bin/bash', 06:35:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:22 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:22 INFO - 'USER': 'cltbld', 06:35:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:22 INFO - 'XPC_FLAGS': '0x0', 06:35:22 INFO - 'XPC_SERVICE_NAME': '0', 06:35:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:22 INFO - Downloading/unpacking psutil>=0.7.1 06:35: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 06:35: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 06:35: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 06:35: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 06:35: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 06:35: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 06:35:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:35:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:35:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:35:25 INFO - Installing collected packages: psutil 06:35:25 INFO - Running setup.py install for psutil 06:35:25 INFO - building 'psutil._psutil_osx' extension 06:35:25 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 06:35:26 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 06:35:26 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 06:35:26 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 06:35:26 INFO - building 'psutil._psutil_posix' extension 06:35:26 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 06:35:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:35:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:35:26 INFO - ^ 06:35:26 INFO - 1 warning generated. 06:35:26 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 06:35:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:35:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:35:26 INFO - Successfully installed psutil 06:35:26 INFO - Cleaning up... 06:35:26 INFO - Return code: 0 06:35:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:35:26 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')]} 06:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:26 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')]} 06:35:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:26 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 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:26 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 06:35:26 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 06:35:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:26 INFO - 'HOME': '/Users/cltbld', 06:35:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:26 INFO - 'LOGNAME': 'cltbld', 06:35:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:26 INFO - 'MOZ_NO_REMOTE': '1', 06:35:26 INFO - 'NO_EM_RESTART': '1', 06:35:26 INFO - 'PAGER': '/bin/cat', 06:35:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:26 INFO - 'PWD': '/builds/slave/test', 06:35:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:26 INFO - 'SHELL': '/bin/bash', 06:35:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:26 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:26 INFO - 'USER': 'cltbld', 06:35:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:26 INFO - 'XPC_FLAGS': '0x0', 06:35:26 INFO - 'XPC_SERVICE_NAME': '0', 06:35:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:35:27 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 06:35:27 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 06:35:27 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 06:35:27 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 06:35:27 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 06:35:27 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 06:35:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:35:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:35:29 INFO - Installing collected packages: mozsystemmonitor 06:35:29 INFO - Running setup.py install for mozsystemmonitor 06:35:29 INFO - Successfully installed mozsystemmonitor 06:35:29 INFO - Cleaning up... 06:35:29 INFO - Return code: 0 06:35:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:35:29 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')]} 06:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:29 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')]} 06:35:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:29 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 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:29 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 06:35:29 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 06:35:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:29 INFO - 'HOME': '/Users/cltbld', 06:35:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:29 INFO - 'LOGNAME': 'cltbld', 06:35:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:29 INFO - 'MOZ_NO_REMOTE': '1', 06:35:29 INFO - 'NO_EM_RESTART': '1', 06:35:29 INFO - 'PAGER': '/bin/cat', 06:35:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:29 INFO - 'PWD': '/builds/slave/test', 06:35:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:29 INFO - 'SHELL': '/bin/bash', 06:35:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:29 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:29 INFO - 'USER': 'cltbld', 06:35:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:29 INFO - 'XPC_FLAGS': '0x0', 06:35:29 INFO - 'XPC_SERVICE_NAME': '0', 06:35:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:30 INFO - Downloading/unpacking blobuploader==1.2.4 06:35:30 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 06:35:30 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 06:35:30 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 06:35:30 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 06:35:30 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 06:35:30 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 06:35:32 INFO - Downloading blobuploader-1.2.4.tar.gz 06:35:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:35:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:35:32 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 06:35:32 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 06:35:32 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 06:35:32 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 06:35:32 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 06:35:32 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 06:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:35:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:35:33 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 06:35:33 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 06:35:33 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 06:35:33 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 06:35:33 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 06:35:33 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 06:35:33 INFO - Downloading docopt-0.6.1.tar.gz 06:35:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:35:33 INFO - Installing collected packages: blobuploader, requests, docopt 06:35:33 INFO - Running setup.py install for blobuploader 06:35:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:35:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:35:34 INFO - Running setup.py install for requests 06:35:34 INFO - Running setup.py install for docopt 06:35:34 INFO - Successfully installed blobuploader requests docopt 06:35:34 INFO - Cleaning up... 06:35:34 INFO - Return code: 0 06:35:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:35:34 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')]} 06:35:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:34 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')]} 06:35:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:34 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 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:34 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 06:35:34 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 06:35:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:34 INFO - 'HOME': '/Users/cltbld', 06:35:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:34 INFO - 'LOGNAME': 'cltbld', 06:35:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:34 INFO - 'MOZ_NO_REMOTE': '1', 06:35:34 INFO - 'NO_EM_RESTART': '1', 06:35:34 INFO - 'PAGER': '/bin/cat', 06:35:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:34 INFO - 'PWD': '/builds/slave/test', 06:35:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:34 INFO - 'SHELL': '/bin/bash', 06:35:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:34 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:34 INFO - 'USER': 'cltbld', 06:35:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:34 INFO - 'XPC_FLAGS': '0x0', 06:35:34 INFO - 'XPC_SERVICE_NAME': '0', 06:35:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-UGJmKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-oYOSG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-hgAkoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-GGX7wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-cZdxrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-suyYQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-KCOWGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-FOhjiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:35:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:35:35 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Y8WqGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-aiZf4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-36iBSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-0aGhqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-19N8uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-n6V1tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-OUvuh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-NAkVus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:35:36 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5rADCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:35:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:35:36 INFO - Running setup.py install for manifestparser 06:35:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:35:37 INFO - Running setup.py install for mozcrash 06:35:37 INFO - Running setup.py install for mozdebug 06:35:37 INFO - Running setup.py install for mozdevice 06:35:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:35:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:35:37 INFO - Running setup.py install for mozfile 06:35:37 INFO - Running setup.py install for mozhttpd 06:35:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:35:37 INFO - Running setup.py install for mozinfo 06:35:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:35:37 INFO - Running setup.py install for mozInstall 06:35:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Running setup.py install for mozleak 06:35:38 INFO - Running setup.py install for mozlog 06:35:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Running setup.py install for moznetwork 06:35:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Running setup.py install for mozprocess 06:35:38 INFO - Running setup.py install for mozprofile 06:35:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:35:38 INFO - Running setup.py install for mozrunner 06:35:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:35:39 INFO - Running setup.py install for mozscreenshot 06:35:39 INFO - Running setup.py install for moztest 06:35:39 INFO - Running setup.py install for mozversion 06:35:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:35:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:35:39 INFO - Cleaning up... 06:35:39 INFO - Return code: 0 06:35:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:35:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:35:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:39 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 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:39 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 06:35:39 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 06:35:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:39 INFO - 'HOME': '/Users/cltbld', 06:35:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:39 INFO - 'LOGNAME': 'cltbld', 06:35:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:39 INFO - 'MOZ_NO_REMOTE': '1', 06:35:39 INFO - 'NO_EM_RESTART': '1', 06:35:39 INFO - 'PAGER': '/bin/cat', 06:35:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:39 INFO - 'PWD': '/builds/slave/test', 06:35:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:39 INFO - 'SHELL': '/bin/bash', 06:35:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:39 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:39 INFO - 'USER': 'cltbld', 06:35:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:39 INFO - 'XPC_FLAGS': '0x0', 06:35:39 INFO - 'XPC_SERVICE_NAME': '0', 06:35:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:35:39 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-RhxRFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:35:39 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)) 06:35:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:35:39 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-HbPIM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Tu84oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-6aTThi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-X0grzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-VQFd6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Sk3bMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-V5ov20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-J1DTA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-rFIwpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:35:40 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)) 06:35:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:35:40 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-PkMI5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:35:41 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)) 06:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:35:41 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-EZAmYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:35:41 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)) 06:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:35:41 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-dRBytw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:35:41 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)) 06:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:35:41 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-snkKso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:35:41 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)) 06:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:41 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-3Ha55R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:35:41 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)) 06:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:35:41 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-wX7eJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:35:41 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)) 06:35:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:35:41 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-1UDsuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:35:41 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)) 06:35:41 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)) 06:35:41 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)) 06:35:41 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)) 06:35:41 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)) 06:35:41 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)) 06:35:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:35:41 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 06:35:41 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 06:35:41 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 06:35:41 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 06:35:41 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 06:35:41 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 06:35:44 INFO - Downloading blessings-1.6.tar.gz 06:35:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:35:44 INFO - Installing collected packages: blessings 06:35:44 INFO - Running setup.py install for blessings 06:35:44 INFO - Successfully installed blessings 06:35:44 INFO - Cleaning up... 06:35:44 INFO - Return code: 0 06:35:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:35:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:35:44 INFO - Reading from file tmpfile_stdout 06:35:44 INFO - Current package versions: 06:35:44 INFO - blessings == 1.6 06:35:44 INFO - blobuploader == 1.2.4 06:35:44 INFO - docopt == 0.6.1 06:35:44 INFO - manifestparser == 1.1 06:35:44 INFO - mozInstall == 1.12 06:35:44 INFO - mozcrash == 0.17 06:35:44 INFO - mozdebug == 0.1 06:35:44 INFO - mozdevice == 0.48 06:35:44 INFO - mozfile == 1.2 06:35:44 INFO - mozhttpd == 0.7 06:35:44 INFO - mozinfo == 0.9 06:35:44 INFO - mozleak == 0.1 06:35:44 INFO - mozlog == 3.1 06:35:44 INFO - moznetwork == 0.27 06:35:44 INFO - mozprocess == 0.22 06:35:44 INFO - mozprofile == 0.28 06:35:44 INFO - mozrunner == 6.11 06:35:44 INFO - mozscreenshot == 0.1 06:35:44 INFO - mozsystemmonitor == 0.0 06:35:44 INFO - moztest == 0.7 06:35:44 INFO - mozversion == 1.4 06:35:44 INFO - psutil == 3.1.1 06:35:44 INFO - requests == 1.2.3 06:35:44 INFO - wsgiref == 0.1.2 06:35:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 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 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:44 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 06:35:44 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 06:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:44 INFO - 'HOME': '/Users/cltbld', 06:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:44 INFO - 'LOGNAME': 'cltbld', 06:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:44 INFO - 'MOZ_NO_REMOTE': '1', 06:35:44 INFO - 'NO_EM_RESTART': '1', 06:35:44 INFO - 'PAGER': '/bin/cat', 06:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:44 INFO - 'PWD': '/builds/slave/test', 06:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:44 INFO - 'SHELL': '/bin/bash', 06:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:44 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:44 INFO - 'USER': 'cltbld', 06:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:44 INFO - 'XPC_FLAGS': '0x0', 06:35:44 INFO - 'XPC_SERVICE_NAME': '0', 06:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 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)) 06:35:44 INFO - Cleaning up... 06:35:44 INFO - Return code: 0 06:35:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:44 INFO - 'HOME': '/Users/cltbld', 06:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:44 INFO - 'LOGNAME': 'cltbld', 06:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:44 INFO - 'MOZ_NO_REMOTE': '1', 06:35:44 INFO - 'NO_EM_RESTART': '1', 06:35:44 INFO - 'PAGER': '/bin/cat', 06:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:44 INFO - 'PWD': '/builds/slave/test', 06:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:44 INFO - 'SHELL': '/bin/bash', 06:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:44 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:44 INFO - 'USER': 'cltbld', 06:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:44 INFO - 'XPC_FLAGS': '0x0', 06:35:44 INFO - 'XPC_SERVICE_NAME': '0', 06:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:45 INFO - Downloading/unpacking jsonschema 06:35:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:35:47 INFO - Downloading/unpacking functools32 (from jsonschema) 06:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:48 INFO - Downloading functools32-3.2.3-2.tar.gz 06:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:35:48 INFO - warning: no files found matching '*.txt' 06:35:48 INFO - no previously-included directories found matching 'build' 06:35:48 INFO - no previously-included directories found matching 'dist' 06:35:48 INFO - no previously-included directories found matching '.git*' 06:35:48 INFO - Installing collected packages: jsonschema, functools32 06:35:48 INFO - Running setup.py install for functools32 06:35:48 INFO - warning: no files found matching '*.txt' 06:35:48 INFO - no previously-included directories found matching 'build' 06:35:48 INFO - no previously-included directories found matching 'dist' 06:35:48 INFO - no previously-included directories found matching '.git*' 06:35:48 INFO - Successfully installed jsonschema functools32 06:35:48 INFO - Cleaning up... 06:35:48 INFO - Return code: 0 06:35:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:35: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')]} 06:35:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35: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')]} 06:35:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:35:48 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 0x10359ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1035ab420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82035b0ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, '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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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 06:35:48 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 06:35:48 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 06:35:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:35:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:35:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:35:48 INFO - 'HOME': '/Users/cltbld', 06:35:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:35:48 INFO - 'LOGNAME': 'cltbld', 06:35:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:48 INFO - 'MOZ_NO_REMOTE': '1', 06:35:48 INFO - 'NO_EM_RESTART': '1', 06:35:48 INFO - 'PAGER': '/bin/cat', 06:35:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:35:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:35:48 INFO - 'PWD': '/builds/slave/test', 06:35:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:35:48 INFO - 'SHELL': '/bin/bash', 06:35:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:35:48 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:35:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:35:48 INFO - 'USER': 'cltbld', 06:35:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:35:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:35:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:35:48 INFO - 'XPC_FLAGS': '0x0', 06:35:48 INFO - 'XPC_SERVICE_NAME': '0', 06:35:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:35:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:48 INFO - Downloading/unpacking flake8 06:35:48 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 06:35:48 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 06:35:48 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 06:35:48 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 06:35:48 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 06:35:48 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 06:35:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:35:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:35:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:35:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:35:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:35:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:35:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:35:52 INFO - no previously-included directories found matching 'docs/_build' 06:35:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:35:52 INFO - Running setup.py install for pep8 06:35:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:35:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:35:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:35:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:35:52 INFO - no previously-included directories found matching 'docs/_build' 06:35:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:35:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:35:52 INFO - Cleaning up... 06:35:52 INFO - Return code: 0 06:35:52 INFO - Running post-action listener: _resource_record_post_action 06:35:52 INFO - Running post-action listener: _start_resource_monitoring 06:35:52 INFO - Starting resource monitoring. 06:35:52 INFO - ##### 06:35:52 INFO - ##### Running install step. 06:35:52 INFO - ##### 06:35:52 INFO - Running pre-action listener: _resource_record_pre_action 06:35:52 INFO - Running main action method: install 06:35:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:35:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:35:53 INFO - Reading from file tmpfile_stdout 06:35:53 INFO - Detecting whether we're running mozinstall >=1.0... 06:35:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:35:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:35:53 INFO - Reading from file tmpfile_stdout 06:35:53 INFO - Output received: 06:35:53 INFO - Usage: mozinstall [options] installer 06:35:53 INFO - Options: 06:35:53 INFO - -h, --help show this help message and exit 06:35:53 INFO - -d DEST, --destination=DEST 06:35:53 INFO - Directory to install application into. [default: 06:35:53 INFO - "/builds/slave/test"] 06:35:53 INFO - --app=APP Application being installed. [default: firefox] 06:35:53 INFO - mkdir: /builds/slave/test/build/application 06:35:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:35:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:36:26 INFO - Reading from file tmpfile_stdout 06:36:26 INFO - Output received: 06:36:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:36:26 INFO - Running post-action listener: _resource_record_post_action 06:36:26 INFO - ##### 06:36:26 INFO - ##### Running run-tests step. 06:36:26 INFO - ##### 06:36:26 INFO - Running pre-action listener: _resource_record_pre_action 06:36:26 INFO - Running main action method: run_tests 06:36:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:36:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:36:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:36:26 INFO - 2016-04-19 06:36:26.804 screenresolution[2651:31650] starting screenresolution argv=screenresolution get 06:36:26 INFO - 2016-04-19 06:36:26.807 screenresolution[2651:31650] Display 0: 1600x1200x32@50 06:36:26 INFO - 2016-04-19 06:36:26.813 screenresolution[2652:31653] starting screenresolution argv=screenresolution list 06:36:26 INFO - Available Modes on Display 0 06:36:26 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 06:36:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:36:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:36:26 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 06:36:26 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 06:36:27 INFO - Graphics/Displays: 06:36:27 INFO - Intel Iris: 06:36:27 INFO - Chipset Model: Intel Iris 06:36:27 INFO - Type: GPU 06:36:27 INFO - Bus: Built-In 06:36:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:36:27 INFO - Vendor: Intel (0x8086) 06:36:27 INFO - Device ID: 0x0a2e 06:36:27 INFO - Revision ID: 0x0009 06:36:27 INFO - Displays: 06:36:27 INFO - Display: 06:36:27 INFO - Resolution: 1600 x 1200 @ 50 Hz 06:36:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:36:27 INFO - Display Serial Number: 1600x1200 50 06:36:27 INFO - Main Display: Yes 06:36:27 INFO - Mirror: Off 06:36:27 INFO - Online: Yes 06:36:27 INFO - Rotation: Supported 06:36:27 INFO - Return code: 0 06:36:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:36:27 INFO - Python 2.7.3 06:36:27 INFO - Return code: 0 06:36:27 INFO - grabbing minidump binary from tooltool 06:36:27 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')]} 06:36:27 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 0x10343ae00>, '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 0x103263db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103435b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:36:27 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 06:36:27 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 06:36:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:36:27 INFO - Return code: 0 06:36:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:36:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:36:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:36:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:36:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:36:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:36:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:36:28 INFO - Return code: 0 06:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 06:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 06:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VVcR9UfxyQ/Render', 06:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:36:28 INFO - 'HOME': '/Users/cltbld', 06:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:36:28 INFO - 'LOGNAME': 'cltbld', 06:36:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:36:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:36:28 INFO - 'MOZ_NO_REMOTE': '1', 06:36:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:36:28 INFO - 'NO_EM_RESTART': '1', 06:36:28 INFO - 'PAGER': '/bin/cat', 06:36:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:36:28 INFO - 'PWD': '/builds/slave/test', 06:36:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:36:28 INFO - 'SHELL': '/bin/bash', 06:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qpbmaQoxeR/Listeners', 06:36:28 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 06:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:36:28 INFO - 'USER': 'cltbld', 06:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:36:28 INFO - 'XPC_FLAGS': '0x0', 06:36:28 INFO - 'XPC_SERVICE_NAME': '0', 06:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:36:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 06:36:28 INFO - mozversion application_buildid: 20160419032946 06:36:28 INFO - mozversion application_changeset: 29d7a174bdb4f63cb198b639d62a80fc6ea02dc6 06:36:28 INFO - mozversion application_display_name: Nightly 06:36:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:36:28 INFO - mozversion application_name: Firefox 06:36:28 INFO - mozversion application_remotingname: firefox 06:36:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:36:28 INFO - mozversion application_vendor: Mozilla 06:36:28 INFO - mozversion application_version: 48.0a1 06:36:28 INFO - mozversion platform_buildid: 20160419032946 06:36:28 INFO - mozversion platform_changeset: 29d7a174bdb4f63cb198b639d62a80fc6ea02dc6 06:36:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:36:28 INFO - mozversion platform_version: 48.0a1 06:36:28 INFO - using testdate: 1461072988 06:36:28 INFO - actual date: 1461072988 06:36:28 INFO - starting webserver on 'localhost:53223' 06:36:28 INFO - SUITE-START | Running 2 tests 06:36:28 INFO - TEST-START | dromaeo_css 06:36:28 INFO - operating with platform_type : mac_ 06:36:28 INFO - Initialising browser for dromaeo_css test... 06:36:28 INFO - TEST-INFO | started process 2670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp2enGTL/profile http://localhost:53223/getInfo.html) 06:36:30 INFO - PROCESS | 2670 | __metrics Screen width/height:1600/1200 06:36:30 INFO - PROCESS | 2670 | colorDepth:24 06:36:30 INFO - PROCESS | 2670 | Browser inner width/height: 1024/689 06:36:30 INFO - PROCESS | 2670 | __metrics 06:36:30 INFO - PROCESS | 2670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:36:30 ERROR - PROCESS | 2670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:36:31 INFO - TEST-INFO | 2670: exit 0 06:36:31 INFO - Browser initialized. 06:36:31 INFO - Running cycle 1/1 for dromaeo_css test... 06:36:31 INFO - TEST-INFO | started process 2672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp2enGTL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:36:43 INFO - PROCESS | 2672 | RSS: Main: 237338624 06:36:43 INFO - PROCESS | 2672 | 06:37:34 INFO - PROCESS | 2672 | 1461073054778 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] 06:39:44 INFO - PROCESS | 2672 | Cycle 1(1): loaded http://localhost:53223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53223/tests/dromaeo/cssquery-ext.html) 06:39:46 INFO - PROCESS | 2672 | RSS: Main: 322883584 06:39:46 INFO - PROCESS | 2672 | 06:42:49 INFO - PROCESS | 2672 | Cycle 1(1): loaded http://localhost:53223/tests/dromaeo/cssquery-ext.html (next: http://localhost:53223/tests/dromaeo/cssquery-jquery.html) 06:42:49 INFO - PROCESS | 2672 | RSS: Main: 379383808 06:42:49 INFO - PROCESS | 2672 | 06:45:52 INFO - PROCESS | 2672 | Cycle 1(1): loaded http://localhost:53223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53223/tests/dromaeo/cssquery-mootools.html) 06:45:52 INFO - PROCESS | 2672 | RSS: Main: 374538240 06:45:52 INFO - PROCESS | 2672 | 06:48:54 INFO - PROCESS | 2672 | Cycle 1(1): loaded http://localhost:53223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53223/tests/dromaeo/cssquery-prototype.html) 06:48:55 INFO - PROCESS | 2672 | RSS: Main: 374484992 06:48:55 INFO - PROCESS | 2672 | 06:51:58 INFO - PROCESS | 2672 | Cycle 1(1): loaded http://localhost:53223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53223/tests/dromaeo/cssquery-yui.html) 06:51:59 INFO - PROCESS | 2672 | RSS: Main: 342519808 06:51:59 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | Cycle 1(1): loaded http://localhost:53223/tests/dromaeo/cssquery-yui.html (next: http://localhost:53223/tests/dromaeo/cssquery-dojo.html) 06:55:02 INFO - PROCESS | 2672 | RSS: Main: 403173376 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | __start_tp_report 06:55:02 INFO - PROCESS | 2672 | _x_x_mozilla_page_load 06:55:02 INFO - PROCESS | 2672 | _x_x_mozilla_page_load_details 06:55:02 INFO - PROCESS | 2672 | |i|pagename|runs| 06:55:02 INFO - PROCESS | 2672 | |0;dojo.html;2229.955400891982;3374;3387.407203739345;3394.456886898096;3426.6915977562016;5505.559555235582;5581.330240371156;5583.38582755897;5590.972045139779;5607.299087614053;4621.607163391107;4711.222648263041;5459.989901868157;5585.3297604287445;5599.048161812491;5042.647014708972;5100.056489549445;5102.6428150029515;5102.719350435725;5146.125158723026;5054.696718196901;5066.113430149728;5158.5873130149685;5182.870428239286;5203.037438073969;5134.84595462133;5139.8458046258675;5143.099957507421;5151.6908985461;5204.141316682742;4960.479149689303;5004.9749751251375;5023.8492845214705;5095.261187127846;5162.303089082963;5052.115879721071;5061.974690126563;5067.746612669352;5082.440931497532;5127.077126117298;5531.170324451325;5532.889342213173;5548.6948217848;5554.91667624986;5561.888762224773;5973.671448070354;6043.211194589409;6086.026235802295;6151.261848578154;6165.167702360213;6095.116208149838;6172.783952561684;6174.104754810527;6191.040388739708;6191.4118158774845;5681.176229446748;5709.257796486471;5709.885802283931;5720.08478643415;5740.42595740431;6167.4140956609945;6180.3201647818705;6237.750489980441;6244.313125556185;6260.718267677966;5995.830548711766;6012.0978172194955;6028.97507423739;6033.396660333931;6058.363871793486;6110.908336374958;6117.104537390909;6154.138420621117;6164.630122192682;6189.81430557084;5981.551383646244;6028.517718582503;6063.484603808637;6107.128129456069;6115.13655545823;6095.93802760953;6104.359042300494;6110.327863935053;6136.386361363828;6138.232720909887;4835.6131509479155;4841.225403935424;4845.878853028703;4853.199222128309;4894.951050489521;4316.330968699856;4378.846740364072;4419.027813880941;4439.089986552749;4439.467263928349;5552.750126244328;5553.694546799965;5573.108302671553;5584.301962254718;5590.8043218487155;6112.898653789767;6122.204113465221;6122.2761798032625;6129.859247658277;6146.170267013896;5511.53151982078;5540.14127810182;5541.390447050821;5542.667439953616;5543.085390910535;5223.399309079428;5245.501677340647;5256.342957130359;5319.335083114611;5324.121519949165;6167.691615419211;6216.347283535164;6244.687765611792;6247.219097612799;6254.937450625435;6219.689015549114;6220.533459990428;6226.813195604139;6243.750249990131;6266.467350275096;6150.739098484889;6163.445289923929;6279.905801413074;6286.15136956505;6289.559730818799;6066.241719785027;6068.757249709962;6074.514038876968;6094.085887116968;6126.172966649446;6161.736878796897;6182.350853160354;6195;6212.130301757667;6215.564910456405;5823.18475413451;5912.113183022581;5998;6006.759729610767;6062.545309101747;5161.890193608392;5954.970225148846;5958.731857066356;5979.850503737267;5984.042553191469;5138.306328645585;5853.209816674865;5858.5898987070495;5860.267466566679;5867.647941123546;5812.447817457335;5910.438508341701;5928.67392293428;6041.879162416815;6075.058365953284;5857.648541087548;5862.296524417097;5874.735636896265;5883.558733094949;5939.07944268639 06:55:02 INFO - PROCESS | 2672 | |1;ext.html;500.9055214356629;526.0951163997923;532.8135152696555;535.7428434351511;557.740650597472;28159.29601759957;29618.555721664165;37197.628023719786;38422.80788596053;38439.615603844024;40556.59443405565;41086;41119.766407007766;41186.79406602963;41231.793841030754;8392.20274073962;9386;9594.424334539915;9596.136347728703;9611.711648650551;8384.455010424303;11222.102231821476;11503.309801411942;11531.827022594667;11533.711657208594;11289.983901448868;11441.771164576703;11444.484998175101;11446.370449625267;11448.599299024536;8436.662533498653;8726.825463490759;8728.956355218246;11302.208845380826;11426.314421134717;8481.151884811532;11132.554697812078;11503.712407189803;11509.827352589718;11518.596849110325;37678;38136.85589432294;38209;38237.0440738981;38281.425778613346;3153.195935036574;3182.0771976126894;3209.422303985282;3261.3966416213075;3399.9660003400145;25222.873885630637;26846.328841779112;27304;27921.860390697915;28500.714992850015;2710.362446112082;2728.1679087878188;2793.2737488252997;2820.661520617539;2824.6045553622507;415446;418440;418445;419114;419511.999999997;56897.43102569004;58557.121643175386;58587.414125859046;58617.70691146517;58623.120653190235;62869.05696414557;62900.68549657314;62917;63008;63123.36876631267;16346.34614615426;16594.08732519699;16596.668066638842;16599.419020334473;16603.750943735853;11556.711082222844;11938.58214962472;11941.16411851162;11949;12900.870991290154;14451.566453006217;15156.014859034127;15808.762868556982;16283.02301861892;16308.266128024268;7074.823129421806;7152.356287934111;7160.785176444715;7230.047887070462;7232.566046037255;904.900460949295;919.5448253114618;928.7353104365224;1032.7400571302999;1134.160721066405;20569.897150514153;26016.479670406865;28869.71130288712;29301.56047659287;29645;1012.8227560177087;1107.1032463704425;1137.1982752159709;1182.3674334231146;1236.9196002259823;14982.850171498363;15057.924710376597;15105.12817954116;15186.544403667907;15227.467038653594;594466;596477;603041;605469;607287;57706;58316.4168358311;58338.12492812611;58344.12483812661;58366.7081664589;1314.5202001269467;1459.299536222619;1507.954761357161;1511.0782422722132;1597.001873828857;28305.292367690974;28468.452300835856;28657.570136447554;28709.856450717612;29039.838406463507;35838.283234335686;45933;47719;47818.52181478141;47850;413017;415035;416078;417068.82931171515;417198;26377.868110659325;37281;38154.80922595369;39240;39916.40125398179;41056.384154237116;43832;45774;45980;46038.769806150754;31292;34577.13557161091;35217.64782352144;35879.64120358763;36115;34476.96569102931;34499;34764.47853282152;35204.29591408106;35440.46839297464;34077;34645.307093858486;35035.7737479187;35073.1231719209;35671.46492802662;30702.692973069985;31224.843875780476;31254;31373.529397058606;31507.527387088754;23782.405439864142;23891.402714932265;24001.51996960086;24445.77771111473;24554.877225613756 06:55:02 INFO - PROCESS | 2672 | |2;jquery.html;337.2496195963979;383.6144674602025;390.7147782119054;403.7295012341731;408.33237656431726;14521.53255771457;15451.84548154518;18512.537186570342;20360.08379622917;20429.795702042975;19632.50918727029;19754.012299385045;20039.49901252466;20098.29655962038;20951.37145885622;5829.591928564992;6072.392760723937;6103.45068943795;6107.450329470346;6116.571839971181;4143.0885205254945;5455.563554915617;6118.908216376758;6680;6727.831804204885;6337.778177763779;6592.604443733368;6634.469242460592;6637.8340541486605;6650.634215118166;6616.305287944745;6623.238327592317;6632.237292711304;6634.701438435258;6639.73441062357;4903.63222758291;5513.035218836688;5888.322842873082;5892.116182572606;5897.823065308047;25449.10928117507;25633.35916602081;25818.741812581822;27085.729142708715;27123.72876271232;2066.8346532277383;2135.7970992755663;2198.208644887855;2278.305116939333;2353.635186546088;14858.479953201695;14940.178290194035;15043.849561504463;15113.244337783068;15176.772348414783;1709.324861635604;1729.2823478256507;1746.9343700342781;1775.2538563287587;1802.6935420978466;77901;99722.5013874926;102317;121107;124308.37845810804;11087.611933582286;11106.723516562633;11349.375784331942;11351.829722554168;11520.366379849022;14597.562073137822;14726.558203253919;14739;14745.410183592536;14753.852461475462;8669.609867555975;8849.20357167858;8893.866592001124;8980.8652870207;8984.685536006209;8751.956240218758;8794.076621954731;8817.426867253607;8915.910840891638;8920.197182253629;5711.914321285184;6720.630365329954;6801.829954251084;6818.397676546107;6842.076319696876;1810.3482746211353;1843.6865732825452;1906.980930190708;1917.712343148511;1921.3851567498266;815.6288888555692;938.2634631813993;960.9663661771805;984.5520288268821;1027.8920713325142;17925.64148717018;18711.906440467712;19459.027048647793;19612.90193549023;19646.901765491082;896.4486840593119;942.9009953954736;952.1811242331589;972.6352617768337;988.9159421449258;10294.536745846455;10378.197886570735;10406.479676016168;10409.167266618653;10409.53157107932;590026;594862;601464;613983;631287;102509;131845;135727.71662418882;136674.965753767;141363.88683422675;906.8458362078594;997.4065431068502;1046.2676126711333;1048.208602505119;1049.5801679328329;14291.213983231022;16184.595385114995;16737.91631041837;17798.890113272024;17833.10834458246;37785.62214377931;49654.75172624258;50363.74090647763;50704.47886563409;52052;73063;75801.62099189468;76582;77369.2263077362;78830.81753773583;6117.510599151969;6310.895593271251;7422.14645315774;7555.13115991646;7715.922840771745;11200.43997800123;11571.421428928688;12201;12229.510819567118;12456.065795065226;10545.73635659084;10566.947165264124;10691.30506517074;10705.143588513056;10791.838122428328;10445;10458.058774710313;10475.842862357224;10766.192535559707;10792.082672972885;10704;10950.780984380108;11186.720331991766;11325.093992480746;11462.369569673749;7969.92030079715;8034;8145.592720364077;8180.8363832721825;8353.538130827203;10138.594456221668;10800;11160.665180044612;11192.664220073411;11332.263402878787 06:55:02 INFO - PROCESS | 2672 | |3;mootools.html;1417.5038736442252;1512.296781996372;1586.0800735573364;1862.739216509688;2009.4172689919924;7894.289513943744;8567.528785916773;8825.161609647073;8827.382083254175;8868.689595864145;8535.445196062252;8774.517401542913;8796.384253102286;8798.956005219965;8832.823343533126;2160.5894879972834;2410.6022506286486;2443.584590619599;2447.192426499251;2451.719900492541;2397.184957114581;2415.2754173747894;2441.4872876695913;2441.5361081394567;2441.694788151481;2404.7715467030603;2408.927732168038;2416.915407960722;2437.7196622388474;2442.05980697431;2381.0475809676095;2405.122130422392;2408.771166739157;2411.2284069097896;2412.8914198861094;2310.2838120182796;2391.964120538193;2404.0984630763464;2406.097713357491;2408.2173293679625;7743.883841742322;8017.278444939983;8738.213560779497;8757.430767000189;8830.381873268874;5121.359830021247;5279.41926388097;5284.233786100994;5296.285001524783;5306.6550674206055;3450.6721861423125;3492.109512074405;3533.6819686228364;3543.344481270947;3571.285742851414;976.1312431935582;1015.9390436573756;1018.0430395428348;1023.3603997501561;1045.0594464981502;11246.3814490203;11379.317240965485;11412.657620271406;11610.6516804496;11624;3993.820278087493;4611.654125940501;4648.511906249795;4782.0914026334885;4793.18515852306;10051;10109.989001099979;10117.140043096419;10130.746731331628;10131.392116473035;5334.093204155303;5381.165919282517;5395.136778115561;5399.406065332811;5423.755930983037;4828.03439312139;4831.299461578052;4911.214205727067;4920.655554111249;4928.359313289321;3209.3741720364774;3781.621837816196;3831.4827498287373;3867.439221312894;3906.9488518528;1113.8941800528937;1271.0848189303872;1280.8142819291152;1281.7695012787829;1312.2061153002296;819.0580832043079;849.5285116760257;879.9868001979976;891.1801142948503;933.6638810028385;5506.779728810803;5524.696141712164;5557.555395568425;5578.665280083208;5771.220885180447;887.6538150121457;952.3428834104445;963.7686955130858;976.4824642939253;977.0522593084696;3774.9433758493647;3786;3895.8831235062994;3920.745151565139;3941.5664276929515;10344.27590068688;10682.57269709203;10838.024577788037;10852.240343175901;10901.618443354602;10281.691549253534;10670.30643008202;10798.190135739851;10812.189085818594;10812.35125892449;7968.203179682101;8085.959570202229;8108.959455202804;8253.257206851293;8268.793280168044;4760.78576464053;5305.204219367126;5612.466815652507;5645.125005624135;5835.67903765297;9742.95128524353;9846.458444785605;9853.950730246303;9912.256580756617;9915.058069483388;9154.67958621445;9686.46724430135;9812.950935245279;9825.312228143961;10028;7375.336219740249;7519.8496030077995;7568.978187944557;7574.962125189339;7588.089429268302;8175.550344731097;8195.016598008277;8257.752267431986;8273.83452330962;8304.377171712267;7376.299251571092;7401.851962960603;7435.8141046474275;7564.357029652541;7572.432067594842;7095.645217739196;7124.3944264736;7192.892106618509;7246.565206087652;7397.149327827368;6832.931670683429;6841.144856892888;6917.377436030782;6920.515563910478;6965.756198533027;3824.6366595173413;4634;4883.584895283941;4897.681650692693;4997.85006449807;4715.41057367829;4844.806207751651;4880.731559764247;4919.877003074952;5010.5240002199735 06:55:02 INFO - PROCESS | 2672 | |4;prototype.html;306.8772491003597;314.2897052660986;323.43721923852496;323.53088022367575;327.77219832216616;5871.911921321178;5952.523798096153;5974.611650242731;5983.132445795357;5987;4943.332650092237;5563.805266815662;5791.942080579193;5796.913046304308;5817.825465236039;4236.978815105936;4341.739495630257;4400.229959757045;4459.104880425823;4490.865274041784;3732.88801335959;4402.3836662867225;4410.162069206856;4439.400680908068;4442.444694413199;3844.9231015379673;4162.438070860426;4378.255696531597;4381.408509851177;4415.845445409412;4329.935050974238;4354.869353919387;4360.956390436103;4364.759938203398;4376.5623437656295;3770.4344348347695;3774.2074164425503;3781.3949768036982;3786.7917264550438;3794.1083845296334;5443.972780136113;5664.51851592611;5686.317641882959;5750.6262092963825;5771.480566748971;5014.373203349582;5016.372953380827;5039.143345631252;5050.494950504957;5066.290719299301;6162.352952939922;6220.813375598739;6222.533310001768;6229.563930524865;6254.46837018854;3568.2506673598577;3869.8839034829;4075.3683179107284;4092.3043082676018;4183.958160418388;6541.313162117956;6583.045458408552;6603.834904127435;6658.766943156967;6667.533272670915;3736.4021756518828;4066.268071747114;4238.17355615652;4264.616184543406;4275.358696195595;3808.6953043756434;3823.8470461181796;4140.503139623264;4321.783910804447;4345.9348109778375;4224.324108142682;5020.5230503102875;5051.2423136529815;5060.5698515625845;5070.518300761421;5563.854928078607;6363.7772677956045;6378.808635740936;6379;6400.199975003125;1234.8703386144505;1392.373431955624;1448.3699590678088;1500.6098414412113;1501.542029680936;1009.4448053570653;1021.667957913681;1033.1506603180223;1039.9792004160026;1045.8901815309434;4725.669203155815;5090.676424129753;5827.24245848037;5843.18195452637;5860.150278209636;5601.411851755588;5651.350094739158;6400;6414.294427612959;6461.61230326182;5253.658512196695;5515.972420137954;5662.405447428043;5818.857816778892;5922.111683247547;2921.9269518262213;3197.3765115802194;3207.93088457948;3455.4989526518516;3507.2459421224557;6562.77030303937;6565.704543295563;6879.690413931285;6879.965600172067;6890.44876409886;5638;5983.012802887475;6152.938470615326;6768.932310676831;6775.62734049632;5672.744726487318;6255.686305875817;6852.45180385568;6872.450203983769;6879;5850.795222167204;6599.987003963837;6618.205815302002;6634.668266586747;6643.1031810704935;6196.915539780611;6207.81376558704;6473;6503.772367967098;6522.869542609216;5654.46282603152;6356.364363563606;6575.079488871466;6901.413379862768;6908.205556360882;3557.9466308005917;3881.55362133347;4110.753354798721;4247.66018718508;4253.851115210953;5259.763310651107;5899.970500147472;6282.308946016026;6320.810375688737;6537.346265373425;5297.655652382737;5605.739637875326;6314.242290925118;6382.648954307558;6383.968080159755;5850.473457388855;6333.588316759396;6363.90454143179;6382.648954307558;6383.361663833765;5028.34631497903;5393.352797664305;6036.426539478743;6229.159063526366;6286.402791734778;5296.523312901857;5307.681539107666;5582.804601838916;6315.915687871124;6363.809085727436;5946;6219.595726277777;6223.719932602953;6236.532260080603;6261.186045814015 06:55:02 INFO - PROCESS | 2672 | |5;yui.html;627.4917316973252;724.5797437486261;756.3004221095489;767.5433117295214;795.8487887301412;12365.505379784809;15191;15241.31414086366;15250.38998440064;15266.847331526682;13927.164370137798;14026.649333766662;14030.228337441436;14038.29808509573;15413.383464661429;912.4616476278994;919.8344298026352;920.6547544670749;921.0973246218709;922.2760133295365;912.5847739278612;922.0456827183866;926.9119433653792;927.3415874728951;927.4713413354391;977.0424983516158;978.2174260591498;978.4765150644407;979.5151400056982;980.3921568627426;971.13568923658;973.0123924216925;974.0113784508694;974.770928831724;976.087358319966;721.1814590439836;722.9349358557755;723.3417589993081;724.7028718225503;728.1517032656948;14777.704445911022;15219.695606087927;15248.923755381153;15282.847171528365;15285.541433757006;12236;12268.509259629604;12462.937685311515;12543.56097536591;13372.598822035354;9890.653827116088;10000.39997600139;10078.798424031478;10257.076863082284;10471.10995565378;3462.7576069675133;3638.126849556114;3639.0720366306687;3650.9269814603826;3657.926841463156;62355.6882215586;82192.58903705444;88724;88789.55605221933;88793.99999999936;9485.146336829719;9498.620055197714;9590.66432674853;9707.6116955321;10018.949905250427;18779;19631.116599753044;20946.371608851758;20973.79026209749;20989.68515472269;6346;7479.513831600929;7571.432142589328;7670.578118203441;7747.612619369009;4095.0990782027907;4140.751554906716;4141.068259641616;4146.771927543954;4146.854860079883;3557.128503516655;3647.3070116677745;3649.3248748981523;3654.3604869148326;3656.067702735785;640.3596403596404;654.9377809108128;656.0356276273869;662.7481557008306;676.4723515657696;499.73264303597404;570.7945139749687;577.8699792546644;580.1254782552443;593.1695626123461;7664;8023.598820058974;8039.115697273295;8080.151584083704;8295.502269863828;482.7176101980311;491.4422130881475;522.0498692379908;587.4008511318432;587.6356658871441;3525.488804123439;3621.27574485104;3691.6862066724093;3700.407934730484;3739.270842185748;66189;90307;92085.53957230171;92437.5378123105;92465.53767231121;74997.6250118746;75712;76157;76347.61826190834;76755;40783;59221.70389148113;59251.33368998405;65747;69637;7975.880361794461;8076.232757887991;8160.714374996846;8236.135205803304;8319.334453243606;37524.62475375211;39097.41353879751;39348.606513934494;40774.59225407826;42729.35905961351;42705;55111.72444137753;55150;56659.300220993435;58878.705606471696;10701.250912436366;10867.945660271647;10916;11087.778244434905;11386.146039046938;11750.471228794895;12286.75426491483;12319.171313476249;12344.691382715504;12669.936650317059;10150.54322555472;10203.03071208234;10492.160627149962;11202.21584489078;11533.653990380302;11143.442827858737;11234.775304494027;11318.981291683458;11650.70873228176;12040.717595248134;11164.944175279072;11291.491882865455;11745.588904388305;11759.118066144902;11843.23019003762;9519.190868776232;9589.520523973633;9838.950805245928;9874.012598740068;9962.402255864672;8833.779155521164;9379.953100234456;9807.9019209807;9875.802483950425;9889.357191782512 06:55:02 INFO - PROCESS | 2672 | __end_tp_report 06:55:02 INFO - PROCESS | 2672 | __start_cc_report 06:55:02 INFO - PROCESS | 2672 | _x_x_mozilla_cycle_collect,1162 06:55:02 INFO - PROCESS | 2672 | __end_cc_report 06:55:02 INFO - PROCESS | 2672 | __startTimestamp1461074102380__endTimestamp 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | ------- Summary: start ------- 06:55:02 INFO - PROCESS | 2672 | Number of tests: 6 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | [#0] dojo.html Cycles:165 Average:5623.52 Median:5860.27 stddev:663.84 (11.3%) stddev-sans-first:610.17 06:55:02 INFO - PROCESS | 2672 | Values: 2230.0 3374.0 3387.4 3394.5 3426.7 5505.6 5581.3 5583.4 5591.0 5607.3 4621.6 4711.2 5460.0 5585.3 5599.0 5042.6 5100.1 5102.6 5102.7 5146.1 5054.7 5066.1 5158.6 5182.9 5203.0 5134.8 5139.8 5143.1 5151.7 5204.1 4960.5 5005.0 5023.8 5095.3 5162.3 5052.1 5062.0 5067.7 5082.4 5127.1 5531.2 5532.9 5548.7 5554.9 5561.9 5973.7 6043.2 6086.0 6151.3 6165.2 6095.1 6172.8 6174.1 6191.0 6191.4 5681.2 5709.3 5709.9 5720.1 5740.4 6167.4 6180.3 6237.8 6244.3 6260.7 5995.8 6012.1 6029.0 6033.4 6058.4 6110.9 6117.1 6154.1 6164.6 6189.8 5981.6 6028.5 6063.5 6107.1 6115.1 6095.9 6104.4 6110.3 6136.4 6138.2 4835.6 4841.2 4845.9 4853.2 4895.0 4316.3 4378.8 4419.0 4439.1 4439.5 5552.8 5553.7 5573.1 5584.3 5590.8 6112.9 6122.2 6122.3 6129.9 6146.2 5511.5 5540.1 5541.4 5542.7 5543.1 5223.4 5245.5 5256.3 5319.3 5324.1 6167.7 6216.3 6244.7 6247.2 6254.9 6219.7 6220.5 6226.8 6243.8 6266.5 6150.7 6163.4 6279.9 6286.2 6289.6 6066.2 6068.8 6074.5 6094.1 6126.2 6161.7 6182.4 6195.0 6212.1 6215.6 5823.2 5912.1 5998.0 6006.8 6062.5 5161.9 5955.0 5958.7 5979.9 5984.0 5138.3 5853.2 5858.6 5860.3 5867.6 5812.4 5910.4 5928.7 6041.9 6075.1 5857.6 5862.3 5874.7 5883.6 5939.1 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | [#1] ext.html Cycles:180 Average:61808.26 Median:34704.89 stddev:129594.75 (373.4%) stddev-sans-first:129876.56 06:55:02 INFO - PROCESS | 2672 | Values: 500.9 526.1 532.8 535.7 557.7 28159.3 29618.6 37197.6 38422.8 38439.6 40556.6 41086.0 41119.8 41186.8 41231.8 8392.2 9386.0 9594.4 9596.1 9611.7 8384.5 11222.1 11503.3 11531.8 11533.7 11290.0 11441.8 11444.5 11446.4 11448.6 8436.7 8726.8 8729.0 11302.2 11426.3 8481.2 11132.6 11503.7 11509.8 11518.6 37678.0 38136.9 38209.0 38237.0 38281.4 3153.2 3182.1 3209.4 3261.4 3400.0 25222.9 26846.3 27304.0 27921.9 28500.7 2710.4 2728.2 2793.3 2820.7 2824.6 415446.0 418440.0 418445.0 419114.0 419512.0 56897.4 58557.1 58587.4 58617.7 58623.1 62869.1 62900.7 62917.0 63008.0 63123.4 16346.3 16594.1 16596.7 16599.4 16603.8 11556.7 11938.6 11941.2 11949.0 12900.9 14451.6 15156.0 15808.8 16283.0 16308.3 7074.8 7152.4 7160.8 7230.0 7232.6 904.9 919.5 928.7 1032.7 1134.2 20569.9 26016.5 28869.7 29301.6 29645.0 1012.8 1107.1 1137.2 1182.4 1236.9 14982.9 15057.9 15105.1 15186.5 15227.5 594466.0 596477.0 603041.0 605469.0 607287.0 57706.0 58316.4 58338.1 58344.1 58366.7 1314.5 1459.3 1508.0 1511.1 1597.0 28305.3 28468.5 28657.6 28709.9 29039.8 35838.3 45933.0 47719.0 47818.5 47850.0 413017.0 415035.0 416078.0 417068.8 417198.0 26377.9 37281.0 38154.8 39240.0 39916.4 41056.4 43832.0 45774.0 45980.0 46038.8 31292.0 34577.1 35217.6 35879.6 36115.0 34477.0 34499.0 34764.5 35204.3 35440.5 34077.0 34645.3 35035.8 35073.1 35671.5 30702.7 31224.8 31254.0 31373.5 31507.5 23782.4 23891.4 24001.5 24445.8 24554.9 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | [#2] jquery.html Cycles:180 Average:34755.03 Median:2315.97 stddev:100934.21 (4358.2%) stddev-sans-first:101184.27 06:55:02 INFO - PROCESS | 2672 | Values: 337.2 383.6 390.7 403.7 408.3 14521.5 15451.8 18512.5 20360.1 20429.8 19632.5 19754.0 20039.5 20098.3 20951.4 5829.6 6072.4 6103.5 6107.5 6116.6 4143.1 5455.6 6118.9 6680.0 6727.8 6337.8 6592.6 6634.5 6637.8 6650.6 6616.3 6623.2 6632.2 6634.7 6639.7 4903.6 5513.0 5888.3 5892.1 5897.8 25449.1 25633.4 25818.7 27085.7 27123.7 2066.8 2135.8 2198.2 2278.3 2353.6 14858.5 14940.2 15043.8 15113.2 15176.8 1709.3 1729.3 1746.9 1775.3 1802.7 77901.0 99722.5 102317.0 121107.0 124308.4 11087.6 11106.7 11349.4 11351.8 11520.4 14597.6 14726.6 14739.0 14745.4 14753.9 8669.6 8849.2 8893.9 8980.9 8984.7 8752.0 8794.1 8817.4 8915.9 8920.2 5711.9 6720.6 6801.8 6818.4 6842.1 1810.3 1843.7 1907.0 1917.7 1921.4 815.6 938.3 961.0 984.6 1027.9 17925.6 18711.9 19459.0 19612.9 19646.9 896.4 942.9 952.2 972.6 988.9 10294.5 10378.2 10406.5 10409.2 10409.5 590026.0 594862.0 601464.0 613983.0 631287.0 102509.0 131845.0 135727.7 136675.0 141363.9 906.8 997.4 1046.3 1048.2 1049.6 14291.2 16184.6 16737.9 17798.9 17833.1 37785.6 49654.8 50363.7 50704.5 52052.0 73063.0 75801.6 76582.0 77369.2 78830.8 6117.5 6310.9 7422.1 7555.1 7715.9 11200.4 11571.4 12201.0 12229.5 12456.1 10545.7 10566.9 10691.3 10705.1 10791.8 10445.0 10458.1 10475.8 10766.2 10792.1 10704.0 10950.8 11186.7 11325.1 11462.4 7969.9 8034.0 8145.6 8180.8 8353.5 10138.6 10800.0 11160.7 11192.7 11332.3 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | [#3] mootools.html Cycles:180 Average:5646.89 Median:4915.55 stddev:3165.55 (64.4%) stddev-sans-first:3158.47 06:55:02 INFO - PROCESS | 2672 | Values: 1417.5 1512.3 1586.1 1862.7 2009.4 7894.3 8567.5 8825.2 8827.4 8868.7 8535.4 8774.5 8796.4 8799.0 8832.8 2160.6 2410.6 2443.6 2447.2 2451.7 2397.2 2415.3 2441.5 2441.5 2441.7 2404.8 2408.9 2416.9 2437.7 2442.1 2381.0 2405.1 2408.8 2411.2 2412.9 2310.3 2392.0 2404.1 2406.1 2408.2 7743.9 8017.3 8738.2 8757.4 8830.4 5121.4 5279.4 5284.2 5296.3 5306.7 3450.7 3492.1 3533.7 3543.3 3571.3 976.1 1015.9 1018.0 1023.4 1045.1 11246.4 11379.3 11412.7 11610.7 11624.0 3993.8 4611.7 4648.5 4782.1 4793.2 10051.0 10110.0 10117.1 10130.7 10131.4 5334.1 5381.2 5395.1 5399.4 5423.8 4828.0 4831.3 4911.2 4920.7 4928.4 3209.4 3781.6 3831.5 3867.4 3906.9 1113.9 1271.1 1280.8 1281.8 1312.2 819.1 849.5 880.0 891.2 933.7 5506.8 5524.7 5557.6 5578.7 5771.2 887.7 952.3 963.8 976.5 977.1 3774.9 3786.0 3895.9 3920.7 3941.6 10344.3 10682.6 10838.0 10852.2 10901.6 10281.7 10670.3 10798.2 10812.2 10812.4 7968.2 8086.0 8109.0 8253.3 8268.8 4760.8 5305.2 5612.5 5645.1 5835.7 9743.0 9846.5 9854.0 9912.3 9915.1 9154.7 9686.5 9813.0 9825.3 10028.0 7375.3 7519.8 7569.0 7575.0 7588.1 8175.6 8195.0 8257.8 8273.8 8304.4 7376.3 7401.9 7435.8 7564.4 7572.4 7095.6 7124.4 7192.9 7246.6 7397.1 6832.9 6841.1 6917.4 6920.5 6965.8 3824.6 4634.0 4883.6 4897.7 4997.9 4715.4 4844.8 4880.7 4919.9 5010.5 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | [#4] prototype.html Cycles:180 Average:5042.80 Median:5603.58 stddev:1603.74 (28.6%) stddev-sans-first:1568.35 06:55:02 INFO - PROCESS | 2672 | Values: 306.9 314.3 323.4 323.5 327.8 5871.9 5952.5 5974.6 5983.1 5987.0 4943.3 5563.8 5791.9 5796.9 5817.8 4237.0 4341.7 4400.2 4459.1 4490.9 3732.9 4402.4 4410.2 4439.4 4442.4 3844.9 4162.4 4378.3 4381.4 4415.8 4329.9 4354.9 4361.0 4364.8 4376.6 3770.4 3774.2 3781.4 3786.8 3794.1 5444.0 5664.5 5686.3 5750.6 5771.5 5014.4 5016.4 5039.1 5050.5 5066.3 6162.4 6220.8 6222.5 6229.6 6254.5 3568.3 3869.9 4075.4 4092.3 4184.0 6541.3 6583.0 6603.8 6658.8 6667.5 3736.4 4066.3 4238.2 4264.6 4275.4 3808.7 3823.8 4140.5 4321.8 4345.9 4224.3 5020.5 5051.2 5060.6 5070.5 5563.9 6363.8 6378.8 6379.0 6400.2 1234.9 1392.4 1448.4 1500.6 1501.5 1009.4 1021.7 1033.2 1040.0 1045.9 4725.7 5090.7 5827.2 5843.2 5860.2 5601.4 5651.4 6400.0 6414.3 6461.6 5253.7 5516.0 5662.4 5818.9 5922.1 2921.9 3197.4 3207.9 3455.5 3507.2 6562.8 6565.7 6879.7 6880.0 6890.4 5638.0 5983.0 6152.9 6768.9 6775.6 5672.7 6255.7 6852.5 6872.5 6879.0 5850.8 6600.0 6618.2 6634.7 6643.1 6196.9 6207.8 6473.0 6503.8 6522.9 5654.5 6356.4 6575.1 6901.4 6908.2 3557.9 3881.6 4110.8 4247.7 4253.9 5259.8 5900.0 6282.3 6320.8 6537.3 5297.7 5605.7 6314.2 6382.6 6384.0 5850.5 6333.6 6363.9 6382.6 6383.4 5028.3 5393.4 6036.4 6229.2 6286.4 5296.5 5307.7 5582.8 6315.9 6363.8 5946.0 6219.6 6223.7 6236.5 6261.2 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - PROCESS | 2672 | [#5] yui.html Cycles:180 Average:17112.62 Median:579.00 stddev:23810.69 (4112.4%) stddev-sans-first:23845.31 06:55:02 INFO - PROCESS | 2672 | Values: 627.5 724.6 756.3 767.5 795.8 12365.5 15191.0 15241.3 15250.4 15266.8 13927.2 14026.6 14030.2 14038.3 15413.4 912.5 919.8 920.7 921.1 922.3 912.6 922.0 926.9 927.3 927.5 977.0 978.2 978.5 979.5 980.4 971.1 973.0 974.0 974.8 976.1 721.2 722.9 723.3 724.7 728.2 14777.7 15219.7 15248.9 15282.8 15285.5 12236.0 12268.5 12462.9 12543.6 13372.6 9890.7 10000.4 10078.8 10257.1 10471.1 3462.8 3638.1 3639.1 3650.9 3657.9 62355.7 82192.6 88724.0 88789.6 88794.0 9485.1 9498.6 9590.7 9707.6 10018.9 18779.0 19631.1 20946.4 20973.8 20989.7 6346.0 7479.5 7571.4 7670.6 7747.6 4095.1 4140.8 4141.1 4146.8 4146.9 3557.1 3647.3 3649.3 3654.4 3656.1 640.4 654.9 656.0 662.7 676.5 499.7 570.8 577.9 580.1 593.2 7664.0 8023.6 8039.1 8080.2 8295.5 482.7 491.4 522.0 587.4 587.6 3525.5 3621.3 3691.7 3700.4 3739.3 66189.0 90307.0 92085.5 92437.5 92465.5 74997.6 75712.0 76157.0 76347.6 76755.0 40783.0 59221.7 59251.3 65747.0 69637.0 7975.9 8076.2 8160.7 8236.1 8319.3 37524.6 39097.4 39348.6 40774.6 42729.4 42705.0 55111.7 55150.0 56659.3 58878.7 10701.3 10867.9 10916.0 11087.8 11386.1 11750.5 12286.8 12319.2 12344.7 12669.9 10150.5 10203.0 10492.2 11202.2 11533.7 11143.4 11234.8 11319.0 11650.7 12040.7 11164.9 11291.5 11745.6 11759.1 11843.2 9519.2 9589.5 9839.0 9874.0 9962.4 8833.8 9380.0 9807.9 9875.8 9889.4 06:55:02 INFO - PROCESS | 2672 | -------- Summary: end -------- 06:55:02 INFO - PROCESS | 2672 | 06:55:02 INFO - TEST-INFO | 2672: exit 0 06:55:02 INFO - TEST-OK | dromaeo_css | took 1114402ms 06:55:02 INFO - TEST-START | kraken 06:55:02 INFO - operating with platform_type : mac_ 06:55:02 INFO - Initialising browser for kraken test... 06:55:02 INFO - TEST-INFO | started process 2675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp1g3qIW/profile http://localhost:53223/getInfo.html) 06:55:05 INFO - PROCESS | 2675 | __metrics Screen width/height:1600/1200 06:55:05 INFO - PROCESS | 2675 | colorDepth:24 06:55:05 INFO - PROCESS | 2675 | Browser inner width/height: 1024/689 06:55:05 INFO - PROCESS | 2675 | __metrics 06:55:05 INFO - PROCESS | 2675 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:55:05 ERROR - PROCESS | 2675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:55:05 INFO - TEST-INFO | 2675: exit 0 06:55:05 INFO - Browser initialized. 06:55:05 INFO - Running cycle 1/1 for kraken test... 06:55:05 INFO - TEST-INFO | started process 2677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp1g3qIW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:55:17 INFO - PROCESS | 2677 | RSS: Main: 237600768 06:55:17 INFO - PROCESS | 2677 | 06:55:20 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53223/tests/kraken/driver.html?testName=audio-beat-detection) 06:55:21 INFO - PROCESS | 2677 | RSS: Main: 286023680 06:55:21 INFO - PROCESS | 2677 | 06:55:23 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53223/tests/kraken/driver.html?testName=audio-dft) 06:55:24 INFO - PROCESS | 2677 | RSS: Main: 298930176 06:55:24 INFO - PROCESS | 2677 | 06:55:28 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53223/tests/kraken/driver.html?testName=audio-fft) 06:55:28 INFO - PROCESS | 2677 | RSS: Main: 384196608 06:55:28 INFO - PROCESS | 2677 | 06:55:30 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53223/tests/kraken/driver.html?testName=audio-oscillator) 06:55:31 INFO - PROCESS | 2677 | RSS: Main: 286244864 06:55:31 INFO - PROCESS | 2677 | 06:55:33 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:55:34 INFO - PROCESS | 2677 | RSS: Main: 290541568 06:55:34 INFO - PROCESS | 2677 | 06:55:36 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53223/tests/kraken/driver.html?testName=imaging-darkroom) 06:55:37 INFO - PROCESS | 2677 | RSS: Main: 328740864 06:55:37 INFO - PROCESS | 2677 | 06:55:40 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53223/tests/kraken/driver.html?testName=imaging-desaturate) 06:55:40 INFO - PROCESS | 2677 | RSS: Main: 329744384 06:55:40 INFO - PROCESS | 2677 | 06:55:43 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53223/tests/kraken/driver.html?testName=json-parse-financial) 06:55:44 INFO - PROCESS | 2677 | RSS: Main: 328474624 06:55:44 INFO - PROCESS | 2677 | 06:55:46 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:55:46 INFO - PROCESS | 2677 | RSS: Main: 319475712 06:55:46 INFO - PROCESS | 2677 | 06:55:48 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:55:49 INFO - PROCESS | 2677 | RSS: Main: 326987776 06:55:49 INFO - PROCESS | 2677 | 06:55:51 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:55:51 INFO - PROCESS | 2677 | RSS: Main: 329457664 06:55:51 INFO - PROCESS | 2677 | 06:55:54 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:55:55 INFO - PROCESS | 2677 | RSS: Main: 334192640 06:55:55 INFO - PROCESS | 2677 | 06:55:58 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:55:58 INFO - PROCESS | 2677 | RSS: Main: 331735040 06:55:58 INFO - PROCESS | 2677 | 06:56:00 INFO - PROCESS | 2677 | Cycle 1(1): loaded http://localhost:53223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53223/tests/kraken/driver.html?testName=ai-astar) 06:56:01 INFO - PROCESS | 2677 | RSS: Main: 396693504 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | __start_tp_report 06:56:01 INFO - PROCESS | 2677 | _x_x_mozilla_page_load 06:56:01 INFO - PROCESS | 2677 | _x_x_mozilla_page_load_details 06:56:01 INFO - PROCESS | 2677 | |i|pagename|runs| 06:56:01 INFO - PROCESS | 2677 | |0;ai-astar;112;111;113;112;113;113;121;113;112;113 06:56:01 INFO - PROCESS | 2677 | |1;audio-beat-detection;154;121;147;186;136;170;158;120;167;122 06:56:01 INFO - PROCESS | 2677 | |2;audio-dft;187;187;190;184;186;185;184;183;188;182 06:56:01 INFO - PROCESS | 2677 | |3;audio-fft;74;74;88;90;56;57;81;98;86;82 06:56:01 INFO - PROCESS | 2677 | |4;audio-oscillator;90;97;137;80;79;81;123;79;77;128 06:56:01 INFO - PROCESS | 2677 | |5;imaging-gaussian-blur;89;85;89;87;88;85;88;88;90;119 06:56:01 INFO - PROCESS | 2677 | |6;imaging-darkroom;94;94;95;94;94;94;95;95;96;94 06:56:01 INFO - PROCESS | 2677 | |7;imaging-desaturate;88;108;142;140;86;83;87;85;87;84 06:56:01 INFO - PROCESS | 2677 | |8;json-parse-financial;60;61;59;58;58;58;59;77;60;58 06:56:01 INFO - PROCESS | 2677 | |9;json-stringify-tinderbox;65;63;73;76;76;71;69;68;68;69 06:56:01 INFO - PROCESS | 2677 | |10;stanford-crypto-aes;83;84;82;83;81;82;82;81;83;84 06:56:01 INFO - PROCESS | 2677 | |11;stanford-crypto-ccm;155;140;150;151;146;135;132;149;188;156 06:56:01 INFO - PROCESS | 2677 | |12;stanford-crypto-pbkdf2;170;172;171;170;171;169;170;172;169;179 06:56:01 INFO - PROCESS | 2677 | |13;stanford-crypto-sha256-iterative;66;70;71;70;72;90;96;97;96;69 06:56:01 INFO - PROCESS | 2677 | __end_tp_report 06:56:01 INFO - PROCESS | 2677 | __start_cc_report 06:56:01 INFO - PROCESS | 2677 | _x_x_mozilla_cycle_collect,1974 06:56:01 INFO - PROCESS | 2677 | __end_cc_report 06:56:01 INFO - PROCESS | 2677 | __startTimestamp1461074161023__endTimestamp 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | ------- Summary: start ------- 06:56:01 INFO - PROCESS | 2677 | Number of tests: 14 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#0] ai-astar Cycles:10 Average:113.30 Median:113.00 stddev:2.79 (2.5%) stddev-sans-first:2.92 06:56:01 INFO - PROCESS | 2677 | Values: 112.0 111.0 113.0 112.0 113.0 113.0 121.0 113.0 112.0 113.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#1] audio-beat-detection Cycles:10 Average:148.10 Median:156.00 stddev:22.99 (14.7%) stddev-sans-first:24.29 06:56:01 INFO - PROCESS | 2677 | Values: 154.0 121.0 147.0 186.0 136.0 170.0 158.0 120.0 167.0 122.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.50 stddev:2.46 (1.3%) stddev-sans-first:2.55 06:56:01 INFO - PROCESS | 2677 | Values: 187.0 187.0 190.0 184.0 186.0 185.0 184.0 183.0 188.0 182.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#3] audio-fft Cycles:10 Average:78.60 Median:84.00 stddev:13.69 (16.3%) stddev-sans-first:14.42 06:56:01 INFO - PROCESS | 2677 | Values: 74.0 74.0 88.0 90.0 56.0 57.0 81.0 98.0 86.0 82.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:79.50 stddev:23.28 (29.3%) stddev-sans-first:24.55 06:56:01 INFO - PROCESS | 2677 | Values: 90.0 97.0 137.0 80.0 79.0 81.0 123.0 79.0 77.0 128.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:88.00 stddev:10.04 (11.4%) stddev-sans-first:10.63 06:56:01 INFO - PROCESS | 2677 | Values: 89.0 85.0 89.0 87.0 88.0 85.0 88.0 88.0 90.0 119.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 06:56:01 INFO - PROCESS | 2677 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 95.0 95.0 96.0 94.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:85.50 stddev:23.25 (27.2%) stddev-sans-first:24.32 06:56:01 INFO - PROCESS | 2677 | Values: 88.0 108.0 142.0 140.0 86.0 83.0 87.0 85.0 87.0 84.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:59.50 stddev:5.79 (9.7%) stddev-sans-first:6.13 06:56:01 INFO - PROCESS | 2677 | Values: 60.0 61.0 59.0 58.0 58.0 58.0 59.0 77.0 60.0 58.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:4.29 (6.1%) stddev-sans-first:4.18 06:56:01 INFO - PROCESS | 2677 | Values: 65.0 63.0 73.0 76.0 76.0 71.0 69.0 68.0 68.0 69.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:1.08 (1.3%) stddev-sans-first:1.13 06:56:01 INFO - PROCESS | 2677 | Values: 83.0 84.0 82.0 83.0 81.0 82.0 82.0 81.0 83.0 84.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:150.50 stddev:15.53 (10.3%) stddev-sans-first:16.38 06:56:01 INFO - PROCESS | 2677 | Values: 155.0 140.0 150.0 151.0 146.0 135.0 132.0 149.0 188.0 156.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:171.00 stddev:2.91 (1.7%) stddev-sans-first:3.05 06:56:01 INFO - PROCESS | 2677 | Values: 170.0 172.0 171.0 170.0 171.0 169.0 170.0 172.0 169.0 179.0 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - PROCESS | 2677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.70 Median:81.00 stddev:13.17 (16.3%) stddev-sans-first:13.01 06:56:01 INFO - PROCESS | 2677 | Values: 66.0 70.0 71.0 70.0 72.0 90.0 96.0 97.0 96.0 69.0 06:56:01 INFO - PROCESS | 2677 | -------- Summary: end -------- 06:56:01 INFO - PROCESS | 2677 | 06:56:01 INFO - TEST-INFO | 2677: exit 0 06:56:01 INFO - TEST-OK | kraken | took 59015ms 06:56:01 INFO - SUITE-END | took 1173s 06:56:02 INFO - Completed test suite (00:19:33) 06:56:02 INFO - javascript benchmark 06:56:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2229.955400891982, 3374.0, 3387.407203739345, 3394.456886898096, 3426.6915977562016, 5505.559555235582, 5581.330240371156, 5583.38582755897, 5590.972045139779, 5607.299087614053, 4621.607163391107, 4711.222648263041, 5459.989901868157, 5585.3297604287445, 5599.048161812491, 5042.647014708972, 5100.056489549445, 5102.6428150029515, 5102.719350435725, 5146.125158723026, 5054.696718196901, 5066.113430149728, 5158.5873130149685, 5182.870428239286, 5203.037438073969, 5134.84595462133, 5139.8458046258675, 5143.099957507421, 5151.6908985461, 5204.141316682742, 4960.479149689303, 5004.9749751251375, 5023.8492845214705, 5095.261187127846, 5162.303089082963, 5052.115879721071, 5061.974690126563, 5067.746612669352, 5082.440931497532, 5127.077126117298, 5531.170324451325, 5532.889342213173, 5548.6948217848, 5554.91667624986, 5561.888762224773, 5973.671448070354, 6043.211194589409, 6086.026235802295, 6151.261848578154, 6165.167702360213, 6095.116208149838, 6172.783952561684, 6174.104754810527, 6191.040388739708, 6191.4118158774845, 5681.176229446748, 5709.257796486471, 5709.885802283931, 5720.08478643415, 5740.42595740431, 6167.4140956609945, 6180.3201647818705, 6237.750489980441, 6244.313125556185, 6260.718267677966, 5995.830548711766, 6012.0978172194955, 6028.97507423739, 6033.396660333931, 6058.363871793486, 6110.908336374958, 6117.104537390909, 6154.138420621117, 6164.630122192682, 6189.81430557084, 5981.551383646244, 6028.517718582503, 6063.484603808637, 6107.128129456069, 6115.13655545823, 6095.93802760953, 6104.359042300494, 6110.327863935053, 6136.386361363828, 6138.232720909887, 4835.6131509479155, 4841.225403935424, 4845.878853028703, 4853.199222128309, 4894.951050489521, 4316.330968699856, 4378.846740364072, 4419.027813880941, 4439.089986552749, 4439.467263928349, 5552.750126244328, 5553.694546799965, 5573.108302671553, 5584.301962254718, 5590.8043218487155, 6112.898653789767, 6122.204113465221, 6122.2761798032625, 6129.859247658277, 6146.170267013896, 5511.53151982078, 5540.14127810182, 5541.390447050821, 5542.667439953616, 5543.085390910535, 5223.399309079428, 5245.501677340647, 5256.342957130359, 5319.335083114611, 5324.121519949165, 6167.691615419211, 6216.347283535164, 6244.687765611792, 6247.219097612799, 6254.937450625435, 6219.689015549114, 6220.533459990428, 6226.813195604139, 6243.750249990131, 6266.467350275096, 6150.739098484889, 6163.445289923929, 6279.905801413074, 6286.15136956505, 6289.559730818799, 6066.241719785027, 6068.757249709962, 6074.514038876968, 6094.085887116968, 6126.172966649446, 6161.736878796897, 6182.350853160354, 6195.0, 6212.130301757667, 6215.564910456405, 5823.18475413451, 5912.113183022581, 5998.0, 6006.759729610767, 6062.545309101747, 5161.890193608392, 5954.970225148846, 5958.731857066356, 5979.850503737267, 5984.042553191469, 5138.306328645585, 5853.209816674865, 5858.5898987070495, 5860.267466566679, 5867.647941123546, 5812.447817457335, 5910.438508341701, 5928.67392293428, 6041.879162416815, 6075.058365953284, 5857.648541087548, 5862.296524417097, 5874.735636896265, 5883.558733094949, 5939.07944268639], "value": 5578.776037468558, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.9055214356629, 526.0951163997923, 532.8135152696555, 535.7428434351511, 557.740650597472, 28159.29601759957, 29618.555721664165, 37197.628023719786, 38422.80788596053, 38439.615603844024, 40556.59443405565, 41086.0, 41119.766407007766, 41186.79406602963, 41231.793841030754, 8392.20274073962, 9386.0, 9594.424334539915, 9596.136347728703, 9611.711648650551, 8384.455010424303, 11222.102231821476, 11503.309801411942, 11531.827022594667, 11533.711657208594, 11289.983901448868, 11441.771164576703, 11444.484998175101, 11446.370449625267, 11448.599299024536, 8436.662533498653, 8726.825463490759, 8728.956355218246, 11302.208845380826, 11426.314421134717, 8481.151884811532, 11132.554697812078, 11503.712407189803, 11509.827352589718, 11518.596849110325, 37678.0, 38136.85589432294, 38209.0, 38237.0440738981, 38281.425778613346, 3153.195935036574, 3182.0771976126894, 3209.422303985282, 3261.3966416213075, 3399.9660003400145, 25222.873885630637, 26846.328841779112, 27304.0, 27921.860390697915, 28500.714992850015, 2710.362446112082, 2728.1679087878188, 2793.2737488252997, 2820.661520617539, 2824.6045553622507, 415446.0, 418440.0, 418445.0, 419114.0, 419511.999999997, 56897.43102569004, 58557.121643175386, 58587.414125859046, 58617.70691146517, 58623.120653190235, 62869.05696414557, 62900.68549657314, 62917.0, 63008.0, 63123.36876631267, 16346.34614615426, 16594.08732519699, 16596.668066638842, 16599.419020334473, 16603.750943735853, 11556.711082222844, 11938.58214962472, 11941.16411851162, 11949.0, 12900.870991290154, 14451.566453006217, 15156.014859034127, 15808.762868556982, 16283.02301861892, 16308.266128024268, 7074.823129421806, 7152.356287934111, 7160.785176444715, 7230.047887070462, 7232.566046037255, 904.900460949295, 919.5448253114618, 928.7353104365224, 1032.7400571302999, 1134.160721066405, 20569.897150514153, 26016.479670406865, 28869.71130288712, 29301.56047659287, 29645.0, 1012.8227560177087, 1107.1032463704425, 1137.1982752159709, 1182.3674334231146, 1236.9196002259823, 14982.850171498363, 15057.924710376597, 15105.12817954116, 15186.544403667907, 15227.467038653594, 594466.0, 596477.0, 603041.0, 605469.0, 607287.0, 57706.0, 58316.4168358311, 58338.12492812611, 58344.12483812661, 58366.7081664589, 1314.5202001269467, 1459.299536222619, 1507.954761357161, 1511.0782422722132, 1597.001873828857, 28305.292367690974, 28468.452300835856, 28657.570136447554, 28709.856450717612, 29039.838406463507, 35838.283234335686, 45933.0, 47719.0, 47818.52181478141, 47850.0, 413017.0, 415035.0, 416078.0, 417068.82931171515, 417198.0, 26377.868110659325, 37281.0, 38154.80922595369, 39240.0, 39916.40125398179, 41056.384154237116, 43832.0, 45774.0, 45980.0, 46038.769806150754, 31292.0, 34577.13557161091, 35217.64782352144, 35879.64120358763, 36115.0, 34476.96569102931, 34499.0, 34764.47853282152, 35204.29591408106, 35440.46839297464, 34077.0, 34645.307093858486, 35035.7737479187, 35073.1231719209, 35671.46492802662, 30702.692973069985, 31224.843875780476, 31254.0, 31373.529397058606, 31507.527387088754, 23782.405439864142, 23891.402714932265, 24001.51996960086, 24445.77771111473, 24554.877225613756], "value": 19290.589447039, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.2496195963979, 383.6144674602025, 390.7147782119054, 403.7295012341731, 408.33237656431726, 14521.53255771457, 15451.84548154518, 18512.537186570342, 20360.08379622917, 20429.795702042975, 19632.50918727029, 19754.012299385045, 20039.49901252466, 20098.29655962038, 20951.37145885622, 5829.591928564992, 6072.392760723937, 6103.45068943795, 6107.450329470346, 6116.571839971181, 4143.0885205254945, 5455.563554915617, 6118.908216376758, 6680.0, 6727.831804204885, 6337.778177763779, 6592.604443733368, 6634.469242460592, 6637.8340541486605, 6650.634215118166, 6616.305287944745, 6623.238327592317, 6632.237292711304, 6634.701438435258, 6639.73441062357, 4903.63222758291, 5513.035218836688, 5888.322842873082, 5892.116182572606, 5897.823065308047, 25449.10928117507, 25633.35916602081, 25818.741812581822, 27085.729142708715, 27123.72876271232, 2066.8346532277383, 2135.7970992755663, 2198.208644887855, 2278.305116939333, 2353.635186546088, 14858.479953201695, 14940.178290194035, 15043.849561504463, 15113.244337783068, 15176.772348414783, 1709.324861635604, 1729.2823478256507, 1746.9343700342781, 1775.2538563287587, 1802.6935420978466, 77901.0, 99722.5013874926, 102317.0, 121107.0, 124308.37845810804, 11087.611933582286, 11106.723516562633, 11349.375784331942, 11351.829722554168, 11520.366379849022, 14597.562073137822, 14726.558203253919, 14739.0, 14745.410183592536, 14753.852461475462, 8669.609867555975, 8849.20357167858, 8893.866592001124, 8980.8652870207, 8984.685536006209, 8751.956240218758, 8794.076621954731, 8817.426867253607, 8915.910840891638, 8920.197182253629, 5711.914321285184, 6720.630365329954, 6801.829954251084, 6818.397676546107, 6842.076319696876, 1810.3482746211353, 1843.6865732825452, 1906.980930190708, 1917.712343148511, 1921.3851567498266, 815.6288888555692, 938.2634631813993, 960.9663661771805, 984.5520288268821, 1027.8920713325142, 17925.64148717018, 18711.906440467712, 19459.027048647793, 19612.90193549023, 19646.901765491082, 896.4486840593119, 942.9009953954736, 952.1811242331589, 972.6352617768337, 988.9159421449258, 10294.536745846455, 10378.197886570735, 10406.479676016168, 10409.167266618653, 10409.53157107932, 590026.0, 594862.0, 601464.0, 613983.0, 631287.0, 102509.0, 131845.0, 135727.71662418882, 136674.965753767, 141363.88683422675, 906.8458362078594, 997.4065431068502, 1046.2676126711333, 1048.208602505119, 1049.5801679328329, 14291.213983231022, 16184.595385114995, 16737.91631041837, 17798.890113272024, 17833.10834458246, 37785.62214377931, 49654.75172624258, 50363.74090647763, 50704.47886563409, 52052.0, 73063.0, 75801.62099189468, 76582.0, 77369.2263077362, 78830.81753773583, 6117.510599151969, 6310.895593271251, 7422.14645315774, 7555.13115991646, 7715.922840771745, 11200.43997800123, 11571.421428928688, 12201.0, 12229.510819567118, 12456.065795065226, 10545.73635659084, 10566.947165264124, 10691.30506517074, 10705.143588513056, 10791.838122428328, 10445.0, 10458.058774710313, 10475.842862357224, 10766.192535559707, 10792.082672972885, 10704.0, 10950.780984380108, 11186.720331991766, 11325.093992480746, 11462.369569673749, 7969.92030079715, 8034.0, 8145.592720364077, 8180.8363832721825, 8353.538130827203, 10138.594456221668, 10800.0, 11160.665180044612, 11192.664220073411, 11332.263402878787], "value": 9620.262703399269, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1417.5038736442252, 1512.296781996372, 1586.0800735573364, 1862.739216509688, 2009.4172689919924, 7894.289513943744, 8567.528785916773, 8825.161609647073, 8827.382083254175, 8868.689595864145, 8535.445196062252, 8774.517401542913, 8796.384253102286, 8798.956005219965, 8832.823343533126, 2160.5894879972834, 2410.6022506286486, 2443.584590619599, 2447.192426499251, 2451.719900492541, 2397.184957114581, 2415.2754173747894, 2441.4872876695913, 2441.5361081394567, 2441.694788151481, 2404.7715467030603, 2408.927732168038, 2416.915407960722, 2437.7196622388474, 2442.05980697431, 2381.0475809676095, 2405.122130422392, 2408.771166739157, 2411.2284069097896, 2412.8914198861094, 2310.2838120182796, 2391.964120538193, 2404.0984630763464, 2406.097713357491, 2408.2173293679625, 7743.883841742322, 8017.278444939983, 8738.213560779497, 8757.430767000189, 8830.381873268874, 5121.359830021247, 5279.41926388097, 5284.233786100994, 5296.285001524783, 5306.6550674206055, 3450.6721861423125, 3492.109512074405, 3533.6819686228364, 3543.344481270947, 3571.285742851414, 976.1312431935582, 1015.9390436573756, 1018.0430395428348, 1023.3603997501561, 1045.0594464981502, 11246.3814490203, 11379.317240965485, 11412.657620271406, 11610.6516804496, 11624.0, 3993.820278087493, 4611.654125940501, 4648.511906249795, 4782.0914026334885, 4793.18515852306, 10051.0, 10109.989001099979, 10117.140043096419, 10130.746731331628, 10131.392116473035, 5334.093204155303, 5381.165919282517, 5395.136778115561, 5399.406065332811, 5423.755930983037, 4828.03439312139, 4831.299461578052, 4911.214205727067, 4920.655554111249, 4928.359313289321, 3209.3741720364774, 3781.621837816196, 3831.4827498287373, 3867.439221312894, 3906.9488518528, 1113.8941800528937, 1271.0848189303872, 1280.8142819291152, 1281.7695012787829, 1312.2061153002296, 819.0580832043079, 849.5285116760257, 879.9868001979976, 891.1801142948503, 933.6638810028385, 5506.779728810803, 5524.696141712164, 5557.555395568425, 5578.665280083208, 5771.220885180447, 887.6538150121457, 952.3428834104445, 963.7686955130858, 976.4824642939253, 977.0522593084696, 3774.9433758493647, 3786.0, 3895.8831235062994, 3920.745151565139, 3941.5664276929515, 10344.27590068688, 10682.57269709203, 10838.024577788037, 10852.240343175901, 10901.618443354602, 10281.691549253534, 10670.30643008202, 10798.190135739851, 10812.189085818594, 10812.35125892449, 7968.203179682101, 8085.959570202229, 8108.959455202804, 8253.257206851293, 8268.793280168044, 4760.78576464053, 5305.204219367126, 5612.466815652507, 5645.125005624135, 5835.67903765297, 9742.95128524353, 9846.458444785605, 9853.950730246303, 9912.256580756617, 9915.058069483388, 9154.67958621445, 9686.46724430135, 9812.950935245279, 9825.312228143961, 10028.0, 7375.336219740249, 7519.8496030077995, 7568.978187944557, 7574.962125189339, 7588.089429268302, 8175.550344731097, 8195.016598008277, 8257.752267431986, 8273.83452330962, 8304.377171712267, 7376.299251571092, 7401.851962960603, 7435.8141046474275, 7564.357029652541, 7572.432067594842, 7095.645217739196, 7124.3944264736, 7192.892106618509, 7246.565206087652, 7397.149327827368, 6832.931670683429, 6841.144856892888, 6917.377436030782, 6920.515563910478, 6965.756198533027, 3824.6366595173413, 4634.0, 4883.584895283941, 4897.681650692693, 4997.85006449807, 4715.41057367829, 4844.806207751651, 4880.731559764247, 4919.877003074952, 5010.5240002199735], "value": 4545.756422502964, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.8772491003597, 314.2897052660986, 323.43721923852496, 323.53088022367575, 327.77219832216616, 5871.911921321178, 5952.523798096153, 5974.611650242731, 5983.132445795357, 5987.0, 4943.332650092237, 5563.805266815662, 5791.942080579193, 5796.913046304308, 5817.825465236039, 4236.978815105936, 4341.739495630257, 4400.229959757045, 4459.104880425823, 4490.865274041784, 3732.88801335959, 4402.3836662867225, 4410.162069206856, 4439.400680908068, 4442.444694413199, 3844.9231015379673, 4162.438070860426, 4378.255696531597, 4381.408509851177, 4415.845445409412, 4329.935050974238, 4354.869353919387, 4360.956390436103, 4364.759938203398, 4376.5623437656295, 3770.4344348347695, 3774.2074164425503, 3781.3949768036982, 3786.7917264550438, 3794.1083845296334, 5443.972780136113, 5664.51851592611, 5686.317641882959, 5750.6262092963825, 5771.480566748971, 5014.373203349582, 5016.372953380827, 5039.143345631252, 5050.494950504957, 5066.290719299301, 6162.352952939922, 6220.813375598739, 6222.533310001768, 6229.563930524865, 6254.46837018854, 3568.2506673598577, 3869.8839034829, 4075.3683179107284, 4092.3043082676018, 4183.958160418388, 6541.313162117956, 6583.045458408552, 6603.834904127435, 6658.766943156967, 6667.533272670915, 3736.4021756518828, 4066.268071747114, 4238.17355615652, 4264.616184543406, 4275.358696195595, 3808.6953043756434, 3823.8470461181796, 4140.503139623264, 4321.783910804447, 4345.9348109778375, 4224.324108142682, 5020.5230503102875, 5051.2423136529815, 5060.5698515625845, 5070.518300761421, 5563.854928078607, 6363.7772677956045, 6378.808635740936, 6379.0, 6400.199975003125, 1234.8703386144505, 1392.373431955624, 1448.3699590678088, 1500.6098414412113, 1501.542029680936, 1009.4448053570653, 1021.667957913681, 1033.1506603180223, 1039.9792004160026, 1045.8901815309434, 4725.669203155815, 5090.676424129753, 5827.24245848037, 5843.18195452637, 5860.150278209636, 5601.411851755588, 5651.350094739158, 6400.0, 6414.294427612959, 6461.61230326182, 5253.658512196695, 5515.972420137954, 5662.405447428043, 5818.857816778892, 5922.111683247547, 2921.9269518262213, 3197.3765115802194, 3207.93088457948, 3455.4989526518516, 3507.2459421224557, 6562.77030303937, 6565.704543295563, 6879.690413931285, 6879.965600172067, 6890.44876409886, 5638.0, 5983.012802887475, 6152.938470615326, 6768.932310676831, 6775.62734049632, 5672.744726487318, 6255.686305875817, 6852.45180385568, 6872.450203983769, 6879.0, 5850.795222167204, 6599.987003963837, 6618.205815302002, 6634.668266586747, 6643.1031810704935, 6196.915539780611, 6207.81376558704, 6473.0, 6503.772367967098, 6522.869542609216, 5654.46282603152, 6356.364363563606, 6575.079488871466, 6901.413379862768, 6908.205556360882, 3557.9466308005917, 3881.55362133347, 4110.753354798721, 4247.66018718508, 4253.851115210953, 5259.763310651107, 5899.970500147472, 6282.308946016026, 6320.810375688737, 6537.346265373425, 5297.655652382737, 5605.739637875326, 6314.242290925118, 6382.648954307558, 6383.968080159755, 5850.473457388855, 6333.588316759396, 6363.90454143179, 6382.648954307558, 6383.361663833765, 5028.34631497903, 5393.352797664305, 6036.426539478743, 6229.159063526366, 6286.402791734778, 5296.523312901857, 5307.681539107666, 5582.804601838916, 6315.915687871124, 6363.809085727436, 5946.0, 6219.595726277777, 6223.719932602953, 6236.532260080603, 6261.186045814015], "value": 4528.443685763268, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.4917316973252, 724.5797437486261, 756.3004221095489, 767.5433117295214, 795.8487887301412, 12365.505379784809, 15191.0, 15241.31414086366, 15250.38998440064, 15266.847331526682, 13927.164370137798, 14026.649333766662, 14030.228337441436, 14038.29808509573, 15413.383464661429, 912.4616476278994, 919.8344298026352, 920.6547544670749, 921.0973246218709, 922.2760133295365, 912.5847739278612, 922.0456827183866, 926.9119433653792, 927.3415874728951, 927.4713413354391, 977.0424983516158, 978.2174260591498, 978.4765150644407, 979.5151400056982, 980.3921568627426, 971.13568923658, 973.0123924216925, 974.0113784508694, 974.770928831724, 976.087358319966, 721.1814590439836, 722.9349358557755, 723.3417589993081, 724.7028718225503, 728.1517032656948, 14777.704445911022, 15219.695606087927, 15248.923755381153, 15282.847171528365, 15285.541433757006, 12236.0, 12268.509259629604, 12462.937685311515, 12543.56097536591, 13372.598822035354, 9890.653827116088, 10000.39997600139, 10078.798424031478, 10257.076863082284, 10471.10995565378, 3462.7576069675133, 3638.126849556114, 3639.0720366306687, 3650.9269814603826, 3657.926841463156, 62355.6882215586, 82192.58903705444, 88724.0, 88789.55605221933, 88793.99999999936, 9485.146336829719, 9498.620055197714, 9590.66432674853, 9707.6116955321, 10018.949905250427, 18779.0, 19631.116599753044, 20946.371608851758, 20973.79026209749, 20989.68515472269, 6346.0, 7479.513831600929, 7571.432142589328, 7670.578118203441, 7747.612619369009, 4095.0990782027907, 4140.751554906716, 4141.068259641616, 4146.771927543954, 4146.854860079883, 3557.128503516655, 3647.3070116677745, 3649.3248748981523, 3654.3604869148326, 3656.067702735785, 640.3596403596404, 654.9377809108128, 656.0356276273869, 662.7481557008306, 676.4723515657696, 499.73264303597404, 570.7945139749687, 577.8699792546644, 580.1254782552443, 593.1695626123461, 7664.0, 8023.598820058974, 8039.115697273295, 8080.151584083704, 8295.502269863828, 482.7176101980311, 491.4422130881475, 522.0498692379908, 587.4008511318432, 587.6356658871441, 3525.488804123439, 3621.27574485104, 3691.6862066724093, 3700.407934730484, 3739.270842185748, 66189.0, 90307.0, 92085.53957230171, 92437.5378123105, 92465.53767231121, 74997.6250118746, 75712.0, 76157.0, 76347.61826190834, 76755.0, 40783.0, 59221.70389148113, 59251.33368998405, 65747.0, 69637.0, 7975.880361794461, 8076.232757887991, 8160.714374996846, 8236.135205803304, 8319.334453243606, 37524.62475375211, 39097.41353879751, 39348.606513934494, 40774.59225407826, 42729.35905961351, 42705.0, 55111.72444137753, 55150.0, 56659.300220993435, 58878.705606471696, 10701.250912436366, 10867.945660271647, 10916.0, 11087.778244434905, 11386.146039046938, 11750.471228794895, 12286.75426491483, 12319.171313476249, 12344.691382715504, 12669.936650317059, 10150.54322555472, 10203.03071208234, 10492.160627149962, 11202.21584489078, 11533.653990380302, 11143.442827858737, 11234.775304494027, 11318.981291683458, 11650.70873228176, 12040.717595248134, 11164.944175279072, 11291.491882865455, 11745.588904388305, 11759.118066144902, 11843.23019003762, 9519.190868776232, 9589.520523973633, 9838.950805245928, 9874.012598740068, 9962.402255864672, 8833.779155521164, 9379.953100234456, 9807.9019209807, 9875.802483950425, 9889.357191782512], "value": 6770.158848061014, "unit": "score"}], "name": "dromaeo_css", "value": 7242.32103980031, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 113.0, 112.0, 113.0, 113.0, 121.0, 113.0, 112.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 147.0, 186.0, 136.0, 170.0, 158.0, 120.0, 167.0, 122.0], "value": 148.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 190.0, 184.0, 186.0, 185.0, 184.0, 183.0, 188.0, 182.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 88.0, 90.0, 56.0, 57.0, 81.0, 98.0, 86.0, 82.0], "value": 78.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 97.0, 137.0, 80.0, 79.0, 81.0, 123.0, 79.0, 77.0, 128.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 87.0, 88.0, 85.0, 88.0, 88.0, 90.0, 119.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 96.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 108.0, 142.0, 140.0, 86.0, 83.0, 87.0, 85.0, 87.0, 84.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 59.0, 58.0, 58.0, 58.0, 59.0, 77.0, 60.0, 58.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 73.0, 76.0, 76.0, 71.0, 69.0, 68.0, 68.0, 69.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 82.0, 83.0, 81.0, 82.0, 82.0, 81.0, 83.0, 84.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 140.0, 150.0, 151.0, 146.0, 135.0, 132.0, 149.0, 188.0, 156.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 171.0, 170.0, 171.0, 169.0, 170.0, 172.0, 169.0, 179.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 71.0, 70.0, 72.0, 90.0, 96.0, 97.0, 96.0, 69.0], "value": 79.7, "unit": "score"}], "name": "kraken", "value": 1521.3, "alertThreshold": 2.0}]} 06:56:02 INFO - Return code: 0 06:56:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:56:02 ERROR - # TBPL SUCCESS # 06:56:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:56:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:56:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:56:02 INFO - 2016-04-19 06:56:02.326 screenresolution[2681:33522] starting screenresolution argv=screenresolution get 06:56:02 INFO - 2016-04-19 06:56:02.328 screenresolution[2681:33522] Display 0: 1600x1200x32@50 06:56:02 INFO - 2016-04-19 06:56:02.334 screenresolution[2682:33525] starting screenresolution argv=screenresolution list 06:56:02 INFO - Available Modes on Display 0 06:56:02 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 06:56:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:56:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:56:02 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 06:56:02 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 06:56:02 INFO - Graphics/Displays: 06:56:02 INFO - Intel Iris: 06:56:02 INFO - Chipset Model: Intel Iris 06:56:02 INFO - Type: GPU 06:56:02 INFO - Bus: Built-In 06:56:02 INFO - VRAM (Dynamic, Max): 1536 MB 06:56:02 INFO - Vendor: Intel (0x8086) 06:56:02 INFO - Device ID: 0x0a2e 06:56:02 INFO - Revision ID: 0x0009 06:56:02 INFO - Displays: 06:56:02 INFO - Display: 06:56:02 INFO - Resolution: 1600 x 1200 @ 50 Hz 06:56:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:56:02 INFO - Display Serial Number: 1600x1200 50 06:56:02 INFO - Main Display: Yes 06:56:02 INFO - Mirror: Off 06:56:02 INFO - Online: Yes 06:56:02 INFO - Rotation: Supported 06:56:02 INFO - Return code: 0 06:56:02 INFO - Running post-action listener: _resource_record_post_action 06:56:02 INFO - Running post-run listener: _resource_record_post_run 06:56:03 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 397312; Write bytes: 692240384; Read time: 4; Write time: 2130 06:56:03 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375235072; Write bytes: 370059264; Read time: 27956; Write time: 811 06:56:03 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 397312; Write bytes: 294901760; Read time: 4; Write time: 1266 06:56:03 INFO - Running post-run listener: _upload_blobber_files 06:56:03 INFO - Blob upload gear active. 06:56:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:56:03 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 06:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:56:04 INFO - (blobuploader) - INFO - Open directory for files ... 06:56:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:56:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:56:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:56:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:56:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:56:05 INFO - (blobuploader) - INFO - Done attempting. 06:56:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:56:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:56:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:56:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:56:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:56:06 INFO - (blobuploader) - INFO - Done attempting. 06:56:06 INFO - (blobuploader) - INFO - Iteration through files over. 06:56:06 INFO - Return code: 0 06:56:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:56:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:56:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba9df4ee681d98fd636b844de1f165a5a0779bf9b187a4cc4842198028b0549a87a0942e0224b7d11eeb0e3bc05132ef78ab49ae9bcfb83ef68945ca5ecc7b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ae172b78edfa3fb18834b26e962e2c8291b26bd7d28ac6d7d971164bd65e3247b5fc7ea1de09ea2402a060190c98a87e1e5a206ab836093f04a226d033b2ef5"} 06:56:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:56:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:56:06 INFO - Contents: 06:56:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba9df4ee681d98fd636b844de1f165a5a0779bf9b187a4cc4842198028b0549a87a0942e0224b7d11eeb0e3bc05132ef78ab49ae9bcfb83ef68945ca5ecc7b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ae172b78edfa3fb18834b26e962e2c8291b26bd7d28ac6d7d971164bd65e3247b5fc7ea1de09ea2402a060190c98a87e1e5a206ab836093f04a226d033b2ef5"} 06:56:06 INFO - Running post-run listener: copy_logs_to_upload_dir 06:56:06 INFO - Copying logs to upload dir... 06:56:06 INFO - mkdir: /builds/slave/test/build/upload/logs 06:56:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.565708 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-19 06:56:06.494653) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 06:56:06.498041) ========= 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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba9df4ee681d98fd636b844de1f165a5a0779bf9b187a4cc4842198028b0549a87a0942e0224b7d11eeb0e3bc05132ef78ab49ae9bcfb83ef68945ca5ecc7b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ae172b78edfa3fb18834b26e962e2c8291b26bd7d28ac6d7d971164bd65e3247b5fc7ea1de09ea2402a060190c98a87e1e5a206ab836093f04a226d033b2ef5"} build_url:https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011044 build_url: 'https://queue.taskcluster.net/v1/task/ANk3zPmdQhWcXkc0cMrojQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ba9df4ee681d98fd636b844de1f165a5a0779bf9b187a4cc4842198028b0549a87a0942e0224b7d11eeb0e3bc05132ef78ab49ae9bcfb83ef68945ca5ecc7b63", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ae172b78edfa3fb18834b26e962e2c8291b26bd7d28ac6d7d971164bd65e3247b5fc7ea1de09ea2402a060190c98a87e1e5a206ab836093f04a226d033b2ef5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 06:56:06.525777) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-19 06:56:06.526071) ========= 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.VVcR9UfxyQ/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.qpbmaQoxeR/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005241 ========= master_lag: 3.77 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-19 06:56:10.299886) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 06:56:10.300236) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 06:56:10.306041) ========= ========= Total master_lag: 3.89 =========