builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0266 starttime: 1469119121.72 results: success (0) buildid: 20160721073720 builduid: 66c3ba6aeda144fc8ac69205b6970116 revision: 6688064e8ad629276830b941df87681a21b67cdc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.722328) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.722762) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.723054) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.745778) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.746191) ========= 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.5Ix713TpiM/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.K3hfelPw3Z/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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-07-21 09:38:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.3M=0.001s 2016-07-21 09:38:41 (10.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122984 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.890614) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.890944) ========= 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.5Ix713TpiM/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.K3hfelPw3Z/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.020187 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:41.939461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 09:38:41.939786) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6688064e8ad629276830b941df87681a21b67cdc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6688064e8ad629276830b941df87681a21b67cdc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5Ix713TpiM/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.K3hfelPw3Z/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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-07-21 09:38:42,014 truncating revision to first 12 chars 2016-07-21 09:38:42,014 Setting DEBUG logging. 2016-07-21 09:38:42,014 attempt 1/10 2016-07-21 09:38:42,014 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6688064e8ad6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 09:38:43,363 unpacking tar archive at: mozilla-beta-6688064e8ad6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.613532 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 09:38:43.575794) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:43.576130) ========= script_repo_revision: 6688064e8ad629276830b941df87681a21b67cdc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:43.576555) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:43.576835) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 09:38:43.594726) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-07-21 09:38:43.595073) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5Ix713TpiM/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.K3hfelPw3Z/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:38:43 INFO - MultiFileLogger online at 20160721 09:38:43 in /builds/slave/test 09:38:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:38:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:38:43 INFO - {'append_to_log': False, 09:38:43 INFO - 'base_work_dir': '/builds/slave/test', 09:38:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:38:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:38:43 INFO - 'branch': 'Mozilla-Beta', 09:38:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:38:43 INFO - 'config_files': ('talos/mac_config.py',), 09:38:43 INFO - 'default_actions': ('clobber', 09:38:43 INFO - 'read-buildbot-config', 09:38:43 INFO - 'download-and-extract', 09:38:43 INFO - 'populate-webroot', 09:38:43 INFO - 'create-virtualenv', 09:38:43 INFO - 'install', 09:38:43 INFO - 'run-tests'), 09:38:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:38:43 INFO - 'download_minidump_stackwalk': True, 09:38:43 INFO - 'download_symbols': 'ondemand', 09:38:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:38:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:38:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:38:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:38:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:38:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:38:43 INFO - 'installer_path': 'installer.exe', 09:38:43 INFO - 'log_level': 'info', 09:38:43 INFO - 'log_name': 'talos', 09:38:43 INFO - 'log_to_console': True, 09:38:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:38:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:38:43 INFO - 'opt_config_files': (), 09:38:43 INFO - 'pip_index': False, 09:38:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:43 INFO - 'cmd': ('bash', 09:38:43 INFO - '-c', 09:38:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:43 INFO - 'enabled': True, 09:38:43 INFO - 'halt_on_failure': False, 09:38:43 INFO - 'name': 'check_screen_resolution'},), 09:38:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:43 INFO - 'cmd': ('bash', 09:38:43 INFO - '-c', 09:38:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:43 INFO - 'enabled': True, 09:38:43 INFO - 'halt_on_failure': False, 09:38:43 INFO - 'name': 'check_screen_resolution'},), 09:38:43 INFO - 'run_cmd_checks_enabled': True, 09:38:43 INFO - 'sps_profile': False, 09:38:43 INFO - 'sps_profile_interval': 0, 09:38:43 INFO - 'suite': 'dromaeojs', 09:38:43 INFO - 'system_bits': '32', 09:38:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:38:43 INFO - 'title': 't-yosemite-r7-0266', 09:38:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:38:43 INFO - 'use_talos_json': True, 09:38:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:38:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:38:43 INFO - 'work_dir': 'build'} 09:38:43 INFO - ##### 09:38:43 INFO - ##### Running clobber step. 09:38:43 INFO - ##### 09:38:43 INFO - Running pre-action listener: _resource_record_pre_action 09:38:43 INFO - Running main action method: clobber 09:38:43 INFO - rmtree: /builds/slave/test/build 09:38:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:38:45 INFO - Running post-action listener: _resource_record_post_action 09:38:45 INFO - ##### 09:38:45 INFO - ##### Running read-buildbot-config step. 09:38:45 INFO - ##### 09:38:45 INFO - Running pre-action listener: _resource_record_pre_action 09:38:45 INFO - Running main action method: read_buildbot_config 09:38:45 INFO - Using buildbot properties: 09:38:45 INFO - { 09:38:45 INFO - "project": "", 09:38:45 INFO - "product": "firefox", 09:38:45 INFO - "script_repo_revision": "production", 09:38:45 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:38:45 INFO - "repository": "", 09:38:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:38:45 INFO - "buildid": "20160721073720", 09:38:45 INFO - "pgo_build": "False", 09:38:45 INFO - "basedir": "/builds/slave/test", 09:38:45 INFO - "buildnumber": 52, 09:38:45 INFO - "slavename": "t-yosemite-r7-0266", 09:38:45 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:38:45 INFO - "platform": "yosemite_r7", 09:38:45 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:38:45 INFO - "branch": "mozilla-beta", 09:38:45 INFO - "repo_path": "releases/mozilla-beta", 09:38:45 INFO - "revision": "6688064e8ad629276830b941df87681a21b67cdc", 09:38:45 INFO - "stage_platform": "macosx64", 09:38:45 INFO - "builduid": "66c3ba6aeda144fc8ac69205b6970116", 09:38:45 INFO - "slavebuilddir": "test" 09:38:45 INFO - } 09:38:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 09:38:45 INFO - Running post-action listener: _resource_record_post_action 09:38:45 INFO - ##### 09:38:45 INFO - ##### Running download-and-extract step. 09:38:45 INFO - ##### 09:38:45 INFO - Running pre-action listener: _resource_record_pre_action 09:38:45 INFO - Running main action method: download_and_extract 09:38:45 INFO - mkdir: /builds/slave/test/build/tests 09:38:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:45 INFO - https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:38:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:38:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:38:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 09:38:46 INFO - Downloaded 1165 bytes. 09:38:46 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:38:46 INFO - Using the following test package requirements: 09:38:46 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:38:46 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:38:46 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 09:38:46 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:38:46 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 09:38:46 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:38:46 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:38:46 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 09:38:46 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:38:46 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:38:46 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 09:38:46 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:38:46 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 09:38:46 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:38:46 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 09:38:46 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:38:46 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 09:38:46 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 09:38:46 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')]} 09:38:46 INFO - https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:38:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:38:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:38:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:38:48 INFO - Downloaded 18314928 bytes. 09:38:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:38:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:38:51 INFO - Return code: 0 09:38:51 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:51 INFO - https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:38:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:38:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:38:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 09:38:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 09:38:53 INFO - Downloaded 18314928 bytes. 09:38:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:38:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:38:56 INFO - Return code: 0 09:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:56 INFO - https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:38:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:38:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:38:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 09:38:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 09:38:58 INFO - Downloaded 10840145 bytes. 09:38:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:38:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:38:58 INFO - Return code: 0 09:38:58 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')]} 09:38:58 INFO - https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 09:38:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:38:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:38:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 09:38:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 09:39:10 INFO - Downloaded 86191973 bytes. 09:39:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:39:10 INFO - mkdir: /builds/slave/test/properties 09:39:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:39:10 INFO - Writing to file /builds/slave/test/properties/build_url 09:39:10 INFO - Contents: 09:39:10 INFO - build_url:https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:39:12 INFO - Running post-action listener: _resource_record_post_action 09:39:12 INFO - Running post-action listener: set_extra_try_arguments 09:39:12 INFO - ##### 09:39:12 INFO - ##### Running populate-webroot step. 09:39:12 INFO - ##### 09:39:12 INFO - Running pre-action listener: _resource_record_pre_action 09:39:12 INFO - Running main action method: populate_webroot 09:39:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:39:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:39:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:39:12 INFO - '--tppagecycles', 09:39:12 INFO - '7'], 09:39:12 INFO - 'tests': ['tsvgm']}}, 09:39:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:39:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:39:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:39:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:39:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['damp', 'tps']}, 09:39:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'tests': ['damp', 'tps']}, 09:39:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['dromaeo_dom']}, 09:39:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:39:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tsvgx', 09:39:12 INFO - 'tsvgr_opacity', 09:39:12 INFO - 'tart', 09:39:12 INFO - 'tscrollx', 09:39:12 INFO - 'cart']}, 09:39:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:39:12 INFO - 'tsvgr_opacity', 09:39:12 INFO - 'tart', 09:39:12 INFO - 'tscrollx', 09:39:12 INFO - 'cart']}, 09:39:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tp5o']}, 09:39:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'tests': ['tp5o']}, 09:39:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s', 09:39:12 INFO - '--xperf_path', 09:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:12 INFO - 'tests': ['tp5n']}, 09:39:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:12 INFO - 'talos_options': ['--xperf_path', 09:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:12 INFO - 'tests': ['tp5n']}}, 09:39:12 INFO - 'talos.zip': {'path': '', 09:39:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:39:12 INFO - Running post-action listener: _resource_record_post_action 09:39:12 INFO - ##### 09:39:12 INFO - ##### Running create-virtualenv step. 09:39:12 INFO - ##### 09:39:12 INFO - Running pre-action listener: _resource_record_pre_action 09:39:12 INFO - Running main action method: create_virtualenv 09:39:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:39:12 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 09:39:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:39:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:39:12 INFO - Using real prefix '/tools/python27' 09:39:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:39:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:39:16 INFO - Installing pip.................done. 09:39:16 INFO - Return code: 0 09:39:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:39:16 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')]} 09:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 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')]} 09:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:16 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 09:39:16 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 09:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:16 INFO - 'HOME': '/Users/cltbld', 09:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:16 INFO - 'LOGNAME': 'cltbld', 09:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:16 INFO - 'MOZ_NO_REMOTE': '1', 09:39:16 INFO - 'NO_EM_RESTART': '1', 09:39:16 INFO - 'PAGER': '/bin/cat', 09:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:16 INFO - 'PWD': '/builds/slave/test', 09:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:16 INFO - 'SHELL': '/bin/bash', 09:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:16 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:16 INFO - 'USER': 'cltbld', 09:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:16 INFO - 'XPC_FLAGS': '0x0', 09:39:16 INFO - 'XPC_SERVICE_NAME': '0', 09:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:16 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 09:39:16 INFO - Cleaning up... 09:39:16 INFO - Return code: 0 09:39:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:39:16 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')]} 09:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 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')]} 09:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:16 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 09:39:16 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 09:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:16 INFO - 'HOME': '/Users/cltbld', 09:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:16 INFO - 'LOGNAME': 'cltbld', 09:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:16 INFO - 'MOZ_NO_REMOTE': '1', 09:39:16 INFO - 'NO_EM_RESTART': '1', 09:39:16 INFO - 'PAGER': '/bin/cat', 09:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:16 INFO - 'PWD': '/builds/slave/test', 09:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:16 INFO - 'SHELL': '/bin/bash', 09:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:16 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:16 INFO - 'USER': 'cltbld', 09:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:16 INFO - 'XPC_FLAGS': '0x0', 09:39:16 INFO - 'XPC_SERVICE_NAME': '0', 09:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:16 INFO - Downloading/unpacking psutil>=0.7.1 09:39:16 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 09:39:16 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 09:39:16 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 09:39:16 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 09:39:16 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 09:39:16 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 09:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:39:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:19 INFO - Installing collected packages: psutil 09:39:19 INFO - Running setup.py install for psutil 09:39:19 INFO - building 'psutil._psutil_osx' extension 09:39:19 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 09:39:19 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 09:39:19 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 09:39:20 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 09:39:20 INFO - building 'psutil._psutil_posix' extension 09:39:20 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 09:39:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:39:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:39:20 INFO - ^ 09:39:20 INFO - 1 warning generated. 09:39:20 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 09:39:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:20 INFO - Successfully installed psutil 09:39:20 INFO - Cleaning up... 09:39:20 INFO - Return code: 0 09:39:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:39:20 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')]} 09:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:20 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')]} 09:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:20 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:20 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 09:39:20 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 09:39:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:20 INFO - 'HOME': '/Users/cltbld', 09:39:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:20 INFO - 'LOGNAME': 'cltbld', 09:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:20 INFO - 'MOZ_NO_REMOTE': '1', 09:39:20 INFO - 'NO_EM_RESTART': '1', 09:39:20 INFO - 'PAGER': '/bin/cat', 09:39:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:20 INFO - 'PWD': '/builds/slave/test', 09:39:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:20 INFO - 'SHELL': '/bin/bash', 09:39:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:20 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:20 INFO - 'USER': 'cltbld', 09:39:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:20 INFO - 'XPC_FLAGS': '0x0', 09:39:20 INFO - 'XPC_SERVICE_NAME': '0', 09:39:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:39:20 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 09:39:20 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 09:39:20 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 09:39:20 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 09:39:20 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 09:39:20 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 09:39:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:39:23 INFO - Installing collected packages: mozsystemmonitor 09:39:23 INFO - Running setup.py install for mozsystemmonitor 09:39:23 INFO - Successfully installed mozsystemmonitor 09:39:23 INFO - Cleaning up... 09:39:23 INFO - Return code: 0 09:39:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:39:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:23 INFO - 'HOME': '/Users/cltbld', 09:39:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:23 INFO - 'LOGNAME': 'cltbld', 09:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:23 INFO - 'MOZ_NO_REMOTE': '1', 09:39:23 INFO - 'NO_EM_RESTART': '1', 09:39:23 INFO - 'PAGER': '/bin/cat', 09:39:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:23 INFO - 'PWD': '/builds/slave/test', 09:39:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:23 INFO - 'SHELL': '/bin/bash', 09:39:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:23 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:23 INFO - 'USER': 'cltbld', 09:39:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:23 INFO - 'XPC_FLAGS': '0x0', 09:39:23 INFO - 'XPC_SERVICE_NAME': '0', 09:39:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:23 INFO - Downloading/unpacking blobuploader==1.2.4 09:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:39:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:39:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:27 INFO - Downloading docopt-0.6.1.tar.gz 09:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:39:27 INFO - Installing collected packages: blobuploader, requests, docopt 09:39:27 INFO - Running setup.py install for blobuploader 09:39:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:39:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:39:27 INFO - Running setup.py install for requests 09:39:28 INFO - Running setup.py install for docopt 09:39:28 INFO - Successfully installed blobuploader requests docopt 09:39:28 INFO - Cleaning up... 09:39:28 INFO - Return code: 0 09:39:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:28 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 09:39:28 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 09:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:28 INFO - 'HOME': '/Users/cltbld', 09:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:28 INFO - 'LOGNAME': 'cltbld', 09:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:28 INFO - 'MOZ_NO_REMOTE': '1', 09:39:28 INFO - 'NO_EM_RESTART': '1', 09:39:28 INFO - 'PAGER': '/bin/cat', 09:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:28 INFO - 'PWD': '/builds/slave/test', 09:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:28 INFO - 'SHELL': '/bin/bash', 09:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:28 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:28 INFO - 'USER': 'cltbld', 09:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:28 INFO - 'XPC_FLAGS': '0x0', 09:39:28 INFO - 'XPC_SERVICE_NAME': '0', 09:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:28 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-7qE7KA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:28 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-iyp5lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:28 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-k_XIeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:28 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-RlDJ_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:28 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-SqbWau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-4rMPeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-CSrV8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Ywl4Ty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-hdUMRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-CbIQgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-qafJrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-KPDqWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-rvwu8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:29 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-voZdc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:30 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Z6qJCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:30 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-WZpWFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:30 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-OITJBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:39:30 INFO - Running setup.py install for manifestparser 09:39:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:39:30 INFO - Running setup.py install for mozcrash 09:39:30 INFO - Running setup.py install for mozdebug 09:39:30 INFO - Running setup.py install for mozdevice 09:39:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:39:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:39:30 INFO - Running setup.py install for mozfile 09:39:31 INFO - Running setup.py install for mozhttpd 09:39:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for mozinfo 09:39:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for mozInstall 09:39:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for mozleak 09:39:31 INFO - Running setup.py install for mozlog 09:39:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for moznetwork 09:39:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for mozprocess 09:39:32 INFO - Running setup.py install for mozprofile 09:39:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Running setup.py install for mozrunner 09:39:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Running setup.py install for mozscreenshot 09:39:32 INFO - Running setup.py install for moztest 09:39:32 INFO - Running setup.py install for mozversion 09:39:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:39:32 INFO - Cleaning up... 09:39:32 INFO - Return code: 0 09:39:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:32 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')]} 09:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:32 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')]} 09:39:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:32 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:32 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 09:39:32 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 09:39:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:32 INFO - 'HOME': '/Users/cltbld', 09:39:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:32 INFO - 'LOGNAME': 'cltbld', 09:39:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:32 INFO - 'MOZ_NO_REMOTE': '1', 09:39:32 INFO - 'NO_EM_RESTART': '1', 09:39:32 INFO - 'PAGER': '/bin/cat', 09:39:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:32 INFO - 'PWD': '/builds/slave/test', 09:39:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:32 INFO - 'SHELL': '/bin/bash', 09:39:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:32 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:32 INFO - 'USER': 'cltbld', 09:39:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:32 INFO - 'XPC_FLAGS': '0x0', 09:39:32 INFO - 'XPC_SERVICE_NAME': '0', 09:39:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-hYdJQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-jra6bC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-ct58Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-RGvfmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-T72Img-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Mngyzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-RaPFMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-n8HXCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:33 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-1l_grf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-8q0obd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-RK7czG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-DmWEUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-UORlns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-IzaOyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-bLR0mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-nM7mlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:34 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-GPcUbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:37 INFO - Downloading blessings-1.6.tar.gz 09:39:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:39:37 INFO - Installing collected packages: blessings 09:39:37 INFO - Running setup.py install for blessings 09:39:37 INFO - Successfully installed blessings 09:39:37 INFO - Cleaning up... 09:39:37 INFO - Return code: 0 09:39:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:39:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:37 INFO - Reading from file tmpfile_stdout 09:39:37 INFO - Current package versions: 09:39:37 INFO - blessings == 1.6 09:39:37 INFO - blobuploader == 1.2.4 09:39:37 INFO - docopt == 0.6.1 09:39:37 INFO - manifestparser == 1.1 09:39:37 INFO - mozInstall == 1.12 09:39:37 INFO - mozcrash == 0.17 09:39:37 INFO - mozdebug == 0.1 09:39:37 INFO - mozdevice == 0.48 09:39:37 INFO - mozfile == 1.2 09:39:37 INFO - mozhttpd == 0.7 09:39:37 INFO - mozinfo == 0.9 09:39:37 INFO - mozleak == 0.1 09:39:37 INFO - mozlog == 3.1 09:39:37 INFO - moznetwork == 0.27 09:39:37 INFO - mozprocess == 0.22 09:39:37 INFO - mozprofile == 0.28 09:39:37 INFO - mozrunner == 6.11 09:39:37 INFO - mozscreenshot == 0.1 09:39:37 INFO - mozsystemmonitor == 0.0 09:39:37 INFO - moztest == 0.7 09:39:37 INFO - mozversion == 1.4 09:39:37 INFO - psutil == 3.1.1 09:39:37 INFO - requests == 1.2.3 09:39:37 INFO - wsgiref == 0.1.2 09:39:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:38 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 09:39:38 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 09:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:38 INFO - 'HOME': '/Users/cltbld', 09:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:38 INFO - 'LOGNAME': 'cltbld', 09:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:38 INFO - 'MOZ_NO_REMOTE': '1', 09:39:38 INFO - 'NO_EM_RESTART': '1', 09:39:38 INFO - 'PAGER': '/bin/cat', 09:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:38 INFO - 'PWD': '/builds/slave/test', 09:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:38 INFO - 'SHELL': '/bin/bash', 09:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:38 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:38 INFO - 'USER': 'cltbld', 09:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:38 INFO - 'XPC_FLAGS': '0x0', 09:39:38 INFO - 'XPC_SERVICE_NAME': '0', 09:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 INFO - Cleaning up... 09:39:38 INFO - Return code: 0 09:39:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:38 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 09:39:38 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 09:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:38 INFO - 'HOME': '/Users/cltbld', 09:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:38 INFO - 'LOGNAME': 'cltbld', 09:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:38 INFO - 'MOZ_NO_REMOTE': '1', 09:39:38 INFO - 'NO_EM_RESTART': '1', 09:39:38 INFO - 'PAGER': '/bin/cat', 09:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:38 INFO - 'PWD': '/builds/slave/test', 09:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:38 INFO - 'SHELL': '/bin/bash', 09:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:38 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:38 INFO - 'USER': 'cltbld', 09:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:38 INFO - 'XPC_FLAGS': '0x0', 09:39:38 INFO - 'XPC_SERVICE_NAME': '0', 09:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:38 INFO - Downloading/unpacking jsonschema 09:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:39:41 INFO - Downloading/unpacking functools32 (from jsonschema) 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39:42 INFO - Downloading functools32-3.2.3-2.tar.gz 09:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:39:42 INFO - warning: no files found matching '*.txt' 09:39:42 INFO - no previously-included directories found matching 'build' 09:39:42 INFO - no previously-included directories found matching 'dist' 09:39:42 INFO - no previously-included directories found matching '.git*' 09:39:42 INFO - Installing collected packages: jsonschema, functools32 09:39:42 INFO - Running setup.py install for functools32 09:39:42 INFO - warning: no files found matching '*.txt' 09:39:42 INFO - no previously-included directories found matching 'build' 09:39:42 INFO - no previously-included directories found matching 'dist' 09:39:42 INFO - no previously-included directories found matching '.git*' 09:39:42 INFO - Successfully installed jsonschema functools32 09:39:42 INFO - Cleaning up... 09:39:42 INFO - Return code: 0 09:39:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:42 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 0x1013b9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1013c9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd003dba940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, '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.5Ix713TpiM/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.K3hfelPw3Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 09:39:42 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 09:39:42 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 09:39:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:39:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:42 INFO - 'HOME': '/Users/cltbld', 09:39:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:42 INFO - 'LOGNAME': 'cltbld', 09:39:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:42 INFO - 'MOZ_NO_REMOTE': '1', 09:39:42 INFO - 'NO_EM_RESTART': '1', 09:39:42 INFO - 'PAGER': '/bin/cat', 09:39:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:42 INFO - 'PWD': '/builds/slave/test', 09:39:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:42 INFO - 'SHELL': '/bin/bash', 09:39:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:39:42 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:39:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:42 INFO - 'USER': 'cltbld', 09:39:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:42 INFO - 'XPC_FLAGS': '0x0', 09:39:42 INFO - 'XPC_SERVICE_NAME': '0', 09:39:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:42 INFO - Downloading/unpacking flake8 09:39:42 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 09:39:42 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 09:39:42 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 09:39:42 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 09:39:42 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 09:39:42 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 09:39:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:39:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:39:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:39:46 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 09:39:46 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 09:39:46 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 09:39:46 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 09:39:46 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 09:39:46 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 09:39:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:39:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:39:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:39:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:39:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:39:46 INFO - no previously-included directories found matching 'docs/_build' 09:39:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:39:46 INFO - Running setup.py install for pep8 09:39:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:39:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:39:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:39:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:39:46 INFO - no previously-included directories found matching 'docs/_build' 09:39:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:39:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:39:46 INFO - Cleaning up... 09:39:47 INFO - Return code: 0 09:39:47 INFO - Running post-action listener: _resource_record_post_action 09:39:47 INFO - Running post-action listener: _start_resource_monitoring 09:39:47 INFO - Starting resource monitoring. 09:39:47 INFO - ##### 09:39:47 INFO - ##### Running install step. 09:39:47 INFO - ##### 09:39:47 INFO - Running pre-action listener: _resource_record_pre_action 09:39:47 INFO - Running main action method: install 09:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:47 INFO - Reading from file tmpfile_stdout 09:39:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:39:47 INFO - Reading from file tmpfile_stdout 09:39:47 INFO - Output received: 09:39:47 INFO - Usage: mozinstall [options] installer 09:39:47 INFO - Options: 09:39:47 INFO - -h, --help show this help message and exit 09:39:47 INFO - -d DEST, --destination=DEST 09:39:47 INFO - Directory to install application into. [default: 09:39:47 INFO - "/builds/slave/test"] 09:39:47 INFO - --app=APP Application being installed. [default: firefox] 09:39:47 INFO - mkdir: /builds/slave/test/build/application 09:39:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:40:08 INFO - Reading from file tmpfile_stdout 09:40:08 INFO - Output received: 09:40:08 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:40:08 INFO - Running post-action listener: _resource_record_post_action 09:40:08 INFO - ##### 09:40:08 INFO - ##### Running run-tests step. 09:40:08 INFO - ##### 09:40:08 INFO - Running pre-action listener: _resource_record_pre_action 09:40:08 INFO - Running main action method: run_tests 09:40:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:40:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:40:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:40:08 INFO - 2016-07-21 09:40:08.964 screenresolution[1918:11942] starting screenresolution argv=screenresolution get 09:40:08 INFO - 2016-07-21 09:40:08.967 screenresolution[1918:11942] Display 0: 1600x1200x32@60 09:40:08 INFO - 2016-07-21 09:40:08.973 screenresolution[1919:11944] starting screenresolution argv=screenresolution list 09:40:08 INFO - Available Modes on Display 0 09:40:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:40:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:40:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:40:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:40:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:40:09 INFO - Intel Iris: 09:40:09 INFO - Chipset Model: Intel Iris 09:40:09 INFO - Type: GPU 09:40:09 INFO - Bus: Built-In 09:40:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:40:09 INFO - Vendor: Intel (0x8086) 09:40:09 INFO - Device ID: 0x0a2e 09:40:09 INFO - Revision ID: 0x0009 09:40:09 INFO - Displays: 09:40:09 INFO - Display: 09:40:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:40:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:40:09 INFO - Display Serial Number: 1600x1200 60 09:40:09 INFO - Main Display: Yes 09:40:09 INFO - Mirror: Off 09:40:09 INFO - Online: Yes 09:40:09 INFO - Rotation: Supported 09:40:09 INFO - Return code: 0 09:40:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:40:09 INFO - Python 2.7.3 09:40:09 INFO - Return code: 0 09:40:09 INFO - grabbing minidump binary from tooltool 09:40:09 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')]} 09:40:09 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 0x101258e00>, '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 0x1010c1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101253b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:40:09 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 09:40:09 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 09:40:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:40:09 INFO - Return code: 0 09:40:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:40:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:40:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:40:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:40:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:40:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:40:10 INFO - Return code: 0 09:40:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 09:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0266 --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 09:40:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5Ix713TpiM/Render', 09:40:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:10 INFO - 'HOME': '/Users/cltbld', 09:40:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:10 INFO - 'LOGNAME': 'cltbld', 09:40:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:40:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:10 INFO - 'MOZ_NO_REMOTE': '1', 09:40:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:40:10 INFO - 'NO_EM_RESTART': '1', 09:40:10 INFO - 'PAGER': '/bin/cat', 09:40:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:10 INFO - 'PWD': '/builds/slave/test', 09:40:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:40:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:10 INFO - 'SHELL': '/bin/bash', 09:40:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K3hfelPw3Z/Listeners', 09:40:10 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 09:40:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:10 INFO - 'USER': 'cltbld', 09:40:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:10 INFO - 'XPC_FLAGS': '0x0', 09:40:10 INFO - 'XPC_SERVICE_NAME': '0', 09:40:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 09:40:10 INFO - mozversion application_buildid: 20160721073720 09:40:10 INFO - mozversion application_changeset: 6688064e8ad629276830b941df87681a21b67cdc 09:40:10 INFO - mozversion application_display_name: Firefox 09:40:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:40:10 INFO - mozversion application_name: Firefox 09:40:10 INFO - mozversion application_remotingname: firefox 09:40:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:40:10 INFO - mozversion application_vendor: Mozilla 09:40:10 INFO - mozversion application_version: 48.0 09:40:10 INFO - mozversion platform_buildid: 20160721073720 09:40:10 INFO - mozversion platform_changeset: 6688064e8ad629276830b941df87681a21b67cdc 09:40:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:40:10 INFO - mozversion platform_version: 48.0 09:40:10 INFO - using testdate: 1469119210 09:40:10 INFO - actual date: 1469119210 09:40:10 INFO - starting webserver on 'localhost:50826' 09:40:10 INFO - SUITE-START | Running 2 tests 09:40:10 INFO - TEST-START | dromaeo_css 09:40:10 INFO - operating with platform_type : mac_ 09:40:10 INFO - Initialising browser for dromaeo_css test... 09:40:10 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpXu50HQ/profile http://localhost:50826/getInfo.html) 09:40:12 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 09:40:12 INFO - PROCESS | 1937 | colorDepth:24 09:40:12 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 09:40:12 INFO - PROCESS | 1937 | __metrics 09:40:12 INFO - PROCESS | 1937 | 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" 09:40:12 ERROR - PROCESS | 1937 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:40:13 INFO - TEST-INFO | 1937: exit 0 09:40:13 INFO - Browser initialized. 09:40:13 INFO - Running cycle 1/1 for dromaeo_css test... 09:40:13 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpXu50HQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:40:25 INFO - PROCESS | 1940 | RSS: Main: 160301056 09:40:25 INFO - PROCESS | 1940 | 09:41:16 INFO - PROCESS | 1940 | 1469119276623 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] 09:43:26 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50826/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50826/tests/dromaeo/cssquery-ext.html) 09:43:27 INFO - PROCESS | 1940 | RSS: Main: 300511232 09:43:27 INFO - PROCESS | 1940 | 09:46:30 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50826/tests/dromaeo/cssquery-ext.html (next: http://localhost:50826/tests/dromaeo/cssquery-jquery.html) 09:46:31 INFO - PROCESS | 1940 | RSS: Main: 308363264 09:46:31 INFO - PROCESS | 1940 | 09:49:33 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50826/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50826/tests/dromaeo/cssquery-mootools.html) 09:49:33 INFO - PROCESS | 1940 | RSS: Main: 311087104 09:49:33 INFO - PROCESS | 1940 | 09:52:35 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50826/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50826/tests/dromaeo/cssquery-prototype.html) 09:52:36 INFO - PROCESS | 1940 | RSS: Main: 307916800 09:52:36 INFO - PROCESS | 1940 | 09:55:39 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50826/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50826/tests/dromaeo/cssquery-yui.html) 09:55:40 INFO - PROCESS | 1940 | RSS: Main: 269942784 09:55:40 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | Cycle 1(1): loaded http://localhost:50826/tests/dromaeo/cssquery-yui.html (next: http://localhost:50826/tests/dromaeo/cssquery-dojo.html) 09:58:42 INFO - PROCESS | 1940 | RSS: Main: 315473920 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | __start_tp_report 09:58:42 INFO - PROCESS | 1940 | _x_x_mozilla_page_load 09:58:42 INFO - PROCESS | 1940 | _x_x_mozilla_page_load_details 09:58:42 INFO - PROCESS | 1940 | |i|pagename|runs| 09:58:42 INFO - PROCESS | 1940 | |0;dojo.html;3446.707029902458;3555.92888142237;3566.4828599853004;3571.6964058055064;3642.562892452906;5794.971025144873;5825.5048320892665;5834.620749651275;5836.9124463133085;5839.941600583993;5801.796937107191;5838.737256823442;5846.561507886904;5851.853703657411;5855.268091488564;5232.241325007881;5277.656952298107;5280.973595132019;5323.920141197885;5366.356037275513;5321.228421878825;5370.087085195526;5378.1663842104535;5384.326959130109;5394.730263486829;4434.201843668138;5339.119045357511;5351.384590772072;5354.759035843377;5372.0330340538685;5343.545798607103;5389.299391079174;5392.811251606175;5395.3525576931015;5422.15956526739;5102.515261050195;5278.736063196825;5278.81524146653;5279.313689220415;5300;4993.575546078588;5646.322441307028;5700.173474846166;5730.283714535683;5787.450192231773;6315.517275674821;6329.240491141047;6354.968225158891;6367.554271201017;6383.648899310536;6268.15379923709;6305.148804911324;6347.333529979332;6350.460210882081;6367.331430199809;5865.618734782268;5933.673647949404;5962.135490353961;5970.522358211333;5985.6408615483215;5292.2326262691695;6377.617342959437;6379.489640828722;6387.457066149336;6399.168108146005;6077.6353418795015;6124.930239974474;6321.3994670507145;6333.1766870306565;6345.07996340528;6302.842428939313;6354.618722876642;6362.141110949962;6362.936370636327;6383.39357761012;5294.49702278283;6285.937140628626;6307.148534947723;6342.651154186564;6345.809625711236;6221.782237621662;6318.905216421757;6329.4303512683155;6337.9683101585115;6356.110144579762;4960.429550601655;4993.201087825931;5004;5033.521815427529;5040.924386134211;3796.677282430969;4480;4499.437570303712;4500.909981800346;4536.79584418702;5913.142594323799;5929.436703513159;5934.198883150806;5936.079907614327;5955.76176952917;6210.565260431817;6224.533160013018;6305.558610897193;6324;6325.9051114233325;5935.255377146423;5940.792072277536;5951.732172052268;5987.64074155552;5996.370381110008;5491.588130890199;5506.752196151183;5533.557315414757;5537.031019571559;5552.555795536347;6306.558540902184;6347.492200623939;6411.903821442589;6425.710843012169;6434.935650643527;6289.088082228142;6296.622202667854;6305.90541141874;6321.178246828067;6323.20959880015;6391.456726178327;6404.199475065617;6404.583702059351;6409.871802563829;6416.871662566816;6194.907076393768;6196.039613859859;6286.497080233479;6290.276618188784;6300.306966233802;6242.843928901632;6264.060390941395;6306.936930630635;6311.8106456804535;6312.873742525216;6123.740204357105;6259.812205633838;6279.434850863261;6297.937020629735;6336.239651241881;6198.845028874134;6314.336994615683;6326.936730632635;6328.493720502441;6355.52333574993;6119.173911521888;6150.169727086965;6152.538559607958;6171.506279497719;6186.752529898754;6151.984922487918;6211.223597050369;6267.717952692231;6322.146510221061;6328.208973878265;6156.938430615636;6163.445289923749;6170.444659980623;6194.876102477835;6215.06773983906 09:58:42 INFO - PROCESS | 1940 | |1;ext.html;519.8804275016746;529.7430746088144;593.368063012891;606.7694276175052;621.0280910375262;29804.40391192175;33325;39201.41197882034;40206;40395.19209615806;46635.53364466354;47192.58422247338;47262;47271.81820454482;47468;9874.703758887244;10085.798284034316;10090.848637270446;10104.494775261244;10124.898751012506;9270.165685088341;12078.516859325617;12372.195807272543;12378.195417297848;12387.132900696955;11928.642140735792;11979.101567382437;11986.400679966011;11995.760084798343;12005.27968321899;12035.277883326984;12162.51349946001;12196.14626976112;12202.877971220263;12204.633860984184;10074.395536267848;11952.940235298855;12069.939650301692;12075.516979320817;12079.758404831855;42528;42848.357274640905;42860.14279714388;42965;43051.56948430538;3255.9348813023607;3269.885554005622;3318.203631128536;3482.7736197147105;3638.2541578976247;28804.42391152165;29148.562771558445;30107.548386774215;30726.23184420385;30882.69117308843;2810.676772171206;2835.4045650413436;2840.204742672055;2872.540393537024;2901.2601786544506;496064;496994;498431;499082;499301;37869;38953;69135.65432172907;69625.30374696289;69937.3006269941;37417.43873841895;37575;37769.24461510754;37769.81115094407;37829.62170378316;16737.414190503274;16895.83104168967;16913.492595222164;16926.66146677084;16929.746053809202;13136.474541018251;13358.131721438076;14280.357383917748;14315.355808988621;14402.351894164578;7189.317014883578;8517.48895066298;8541.14588541141;8541.829163416696;8628.913710862937;3470.514128022079;3520.9471857922154;3522.1898963238086;3536.328097661436;3558.91102722434;937.2080591899834;960.7934294126796;1028.6965345223177;1044.639599338227;1160.109022293656;28374.148775536767;28697.282567935552;28762;28769.424611507653;28838.423231535253;1137.175547727907;1185.229600759496;1236.4744983382027;1243.2043492164842;1251.7121062155754;14702.852971470362;15039.849601503845;15042.774358384633;15339.386424542894;15410.922945385202;645690;647649;651330;653734;654028;65742.01386979199;66299.35904486736;66305;66361.33638663648;66758.33241667521;1385.2658091211674;1428.428628548589;1534.9309281082378;1543.814742230917;1689.5438231677379;29432.705672943852;29909.252268693457;29911.401771964873;30123.246918827204;30631.2342191447;52792;52957.20564191463;53533;53570;53572.732136339066;526049;527555;527583;528966;853229;27978.160655180378;28734.137975860758;28817.42365152727;28818.135455936357;28822.711772882;45438.318425222984;47212.819679506916;47420.7628961853;47785.04429911452;49016.5098349012;37670.246595067394;38625.2274954505;38831.805840970614;38832;39465.60534394619;38070.61929380782;38083.809580951915;38639.6136038636;38651.6134838648;39028.41457378085;37803.24393512059;38157.046073848374;38164.42753358757;38430.6156938427;38985.02537436473;32773.32308782357;34002.489962650085;34276;34327;34423.139421514665;24972.625410618493;25334.619980699936;25473.98104075816;25830.483390332465;25875.611865822404 09:58:42 INFO - PROCESS | 1940 | |2;jquery.html;416.7561976243897;422.3896469601425;423.419914716838;425.10727472308156;429.8194758201555;16967;19500.512487187792;20876;20886.89556552219;20909;20852.478688032807;21254.89372553135;21369.67945480819;21401.67897481539;21407.892960535177;5720.1991721158865;5821.15593238979;5940.554458415636;5947.464728174464;5955.285365756115;5904.7047647617665;5954;5977.790777322794;5987.730552125143;5995.640261584319;5728.942710572904;5838.21184140142;5908.822735317947;5917.112433135043;5940.910886336708;4983.277424773406;5814.680192589406;5836.358000619928;5875.9118613220835;5885;5155.304033955405;5569.275994120739;5870.794522191745;5876.353601103875;5877.382874798127;13739.381727822172;13831.930840345834;13886.652833679138;19713.211471541123;24704.3823904402;2151.66649169379;2271.7501074881748;2332.3236261484317;2378.940526486834;2446.8898899549563;9866.963968783246;9994.350367226107;10089.041541053588;10102.191824654083;10335.948320258425;1544.5057581573901;1626.430749237769;1842.1986435900426;1887.9056047197585;1939.2727727102338;119579;121129;124135.9999999991;124303.37848310791;129877;9422.811543769087;11449.771004580029;11671.35807530577;11709.531618735326;11860.762784744256;14962.850371496146;15009.624759380884;15029.173395463136;15050.172240526657;15054.247287635575;8715.694950676696;9035.593398297093;9037;9055.501947392957;9143.817123657622;9117.179453849185;9126.634934602542;9133.442636061913;9214.401063930824;9223.677171299107;4643.233866412004;4701.882452938704;4713.175194341045;4756.857294281177;4770.948492148409;1685.3595633659377;2039.94900127498;2054.9794502054788;2063.566651003276;2084.8220755273223;902.1700035966926;993.145894530703;997.1922742578535;1032.4631191780381;1115.2694984784816;19844;19937;19952.10215540304;20125.09437075335;20251.594968100555;966.1160038564634;1026.4764969865273;1040.7398150462384;1049.3284298049255;1124.696331990358;8312.54281014538;9224.262059035262;10547;10608.992145746142;10612.416317102634;601393;609663;618714;640468;655621;133021;135991.6012797111;138893;139062.60937390497;142159.5784042167;1049.7480604654827;1061.3207547169818;1066.7919755647729;1078.1374900080061;1079.8164312066967;17517.139373588456;17525.737113943047;17655.823441765933;17958.5510362242;18148.637027259643;54585.18122228249;55002.724986374815;55141;55520.72239638776;55623.165652514435;75159.74520764386;76848.67862316986;77104.84342734976;78973.60513197398;80546.59726701329;8274.420790544604;8288.088310285742;8303.6678532858;8416.074231834617;8423.789405264917;9542.42745435276;10412.791744165226;12102.152849300464;12558.87441125577;12741;10905.963933426312;11060.336379817236;11107.222494425312;11215.495302711235;11229;8732.825343493221;10840.837387439353;10972.615958441416;11049;11074.667759967213;11088.279261847954;11089.889101108885;11349.375784331942;11428.25716328436;11585.710357241136;8293.58532073406;8293.58532073406;8379.287760540423;8402.41183117176;8454.154584541742;9819.950900245452;10435.426051567236;10532.525446437352;11256.83114753263;11301 09:58:42 INFO - PROCESS | 1940 | |3;mootools.html;1399.6570840144166;1446.6744982378973;1648.6537827056318;1875.502991707198;2038.9490262743439;8408.621612027458;9100.40847344922;9229.492377919212;9257.305702072346;9261.490618016007;8976.461412315266;9209.12513311236;9220.446773193597;9236.999999999984;9246.676366327163;2558.155808583163;2598.7920966322745;2599.2981894888367;2600.5449046416898;2601.9219423417235;2510.5104504621604;2578.2780821370047;2580.7290234525385;2582.5609646360163;2601.349662584354;2554.246497283306;2580.7290234525385;2595.416031392931;2602.557565213918;2607.2960300718796;2350.6826578411865;2400.8319417640596;2570.2546261584116;2580.2388295452793;2587.4825034993078;2528.3679080229945;2574.613807928807;2578.162097318379;2580.63871058052;2596.5975273832587;8922.86615700765;9111.81776364469;9223.723288301295;9302.023287554777;9308.34841561091;5674.148877668342;5854.941450585482;5860.296764388258;5862.70686465675;5869.090291004901;3717.3308804415196;3756.849726010956;3822.1782316802023;3850.4224366345275;3859.2281543691374;1055.8469021991846;1066.9359838409644;1067.3542506783363;1079.7354648111263;1105.6682995101519;12362.690932726664;12586.307753073577;12605.684857878443;12612.747745045048;12615.927646150089;4178.8328466860985;4757.310190022427;4876.122297986397;4893.951060489349;4926.679765815211;9785.461799601091;9796.755081122887;9808.21534277256;9817.410955342702;9836.508174591241;5601.019821531298;5642;5646.181303710939;5652.547796176296;5661.745221465044;4647.651426143053;4765.11845308619;5099.107656160157;5146.305248791366;5241.030409374202;4058.2492238935893;4146.709730318909;4193.559676233952;4215.114825886537;4225.345071513921;1290.9612711618665;1307.0589175793414;1307.8888294494884;1316.4997301025548;1318.0246617502978;766.7469734987441;904.3127223310331;916.1479823763964;921.5115988526092;973.7273563402259;6128.785492507741;6238.438540531374;6258.405451482102;6278.277998030285;6280.591761535486;473.5430309751107;477.59165913144454;497.7605761653529;497.9875503112451;499.5578912662247;3839.1361943562474;4024.75851448914;4080.755154690728;4081.673466122703;4138.52406973196;11122.833157502644;11540.826887596693;11623.88376116228;11653.18427710069;11695.649130526097;11423.771524569462;11433.542658293742;11454.083673306115;11492.42537873103;11493.367864767519;8767.912320876709;8767.912320876838;8859.51272679996;8862.955685221532;8868.379213454997;5827.883442331215;5947.762089516371;6009.699515024318;6029.8191054268445;6174.969125154345;9885.654002109892;10072.992700730161;10232.64185753495;10238.488075596339;10254.077133058061;8438.451500652438;9935.15551178129;10067.748306292402;10290.073893349634;10384.636537721144;6214.25428948529;7129.928700712927;7314;7792.649330780242;7938.761837144895;7182.6408679564765;8603.397762156588;8665.783355416164;8667.263282620796;8672.132786721277;7571;7727.42044346665;7737.1102323233545;7777.1833957433655;7817.570033648204;7453.217412171643;7558.281963213486;7613.08642962848;7614.086309642878;7775.727849525239;7277.454190935595;7308.5614863108385;7330.010448589373;7334.119905611361;7369.557826530426;4970.776315065758;4976.626752993468;5090.26191202281;5162.174052151638;5190.766415511235;4988.301637770643;4989.151844186569;5016.172331565251;5077.060743762416;5237.580993520433 09:58:42 INFO - PROCESS | 1940 | |4;prototype.html;334.11459631975265;340.90284268983345;343.6477610449289;351.9718422526198;352.85709287738445;6659.533832631711;6706.6981985810635;6719.764808231701;6772.64547090582;6781.525293229476;6616.702248398821;6644.966775166129;6657.167854018247;6665.43343815775;6696.062551242818;4673.43918729752;4787.449443314029;4797.688150270221;4809.78355973982;4819.180739274314;4768.7138771673635;4815.470298267188;4941.357623508939;4968.6025117990475;4986.4514903360605;4066.877993660195;4746.596539294164;4860.635452341071;4897.6081913447015;4911.75441227939;4877.585405240558;4888.242322439992;4894.853154405374;4898.485659005824;4898.583620392271;4019.799010049515;4060.4112403701592;4163.084121493267;4169.895752606179;4202.579742025803;6325.525585581054;6361.777337793202;6367.204099487564;6401.8399540011405;6408.166938297992;5817.389174136697;5823.388544202882;5834.299884013945;5850.795222167247;5851.005329094064;7068.363847253772;7074.665360431045;7074.6816393262425;7074.929250707479;7091.255418181068;4450.732956022616;4732.550407711262;4740.668153229275;4742.264948932921;4744.454387745419;7314.7439839605895;7472.626368681545;7514.0231769870115;7522.247775222489;7534.0582427196605;4718.127146477844;4718.433787945469;4740.051989602093;4741.0754902794315;4744.146053710365;4811.711297322172;4811.927821082687;4812.25410061441;4816.710997340171;4816.8073277069225;5710.8001219957105;5716.742746576331;5719;5728.398518155617;5738.885222295614;6407;6524.7276781028195;6892.655367231719;7107.92892071083;7137.1435427749;1644.2600829626715;1664.9334026639042;1676.4970508847541;1677.3626022111519;1684.0400971446159;1048.8846226915034;1051.9579216831394;1069.2835800014009;1075.9462026898623;1078.223678951154;5505.3393592768325;6539.2479864815205;6722.159730033746;6723.663816809238;6733.3939945405145;7288.08898887639;7382.852342953111;7452.254774522505;7579.583122928181;7583.355414789694;6718.328167183243;6773.9322606774285;6809.353111454404;6824.727010919508;6827.453803695693;3414.7536149305324;3931.1744533647684;3963.7027222958395;3963.8612648557737;3968.6428221460205;7520.1199700074985;7770.572618505923;7778.533288002738;7795.922040779519;7801.765947021599;5600.775968961196;6429.807105786834;7662.73180438682;7686.769396918102;7734.5359278443475;7761.573113478813;7790.766277011699;7798.064232292275;7801.648925798353;7808.882866757003;7484.288992545699;7503.362214211853;7510.248975102446;7521.05986751656;7526.548407095592;7076.398506127037;7335.4498412618195;7345.742898998509;7373.004644372941;7381.630918454163;7747.3802095833325;7764.6894124234395;7784.494007889469;7791.64937577799;7809.726659566888;4906.779194936165;4978.60171186297;4987.650864439454;4994.525520075587;4997.750112494288;7385.224551422023;7396.4082873368925;7402.370798481974;7412.777616671508;7415.2214017527385;6956.582605043714;7147;7168.032315637531;7287.927120728725;7296.890546641911;7135.143782746104;7198.89201661965;7212.675429605575;7223.638818059181;7229;7045.506814522934;7055.047568578177;7065.328793764585;7070.36366726997;7072.5402848814665;7218.566885986857;7250.709971601288;7254.383377412979;7261.056062712025;7275.454340924346;6793.354631310018;7013.053237813034;7104.644767761694;7106.076210092655;7121.893171602534 09:58:42 INFO - PROCESS | 1940 | |5;yui.html;595.1845971019702;611.9724612392439;774.4114473000525;791.6121100660678;796.0328201235498;15107.018043827158;15337.079775213468;15344.386224551032;15344.923275383622;17394.826051739477;16422.917885410556;16433.342666293363;16441.342346306134;16447.58881027975;17368.04475753833;797.6609940775177;812.0296245986049;962.3696478806393;995.2635050062974;998.5356809083775;796.8844517544952;800.9599520024004;803.4255507312271;984.2372161574801;1012.6759436980169;1041.6874937518753;1044.4621020174577;1049.7847941172042;1050.180858930032;1050.8266136087536;1037.3153718545761;1037.3205550364523;1040.043160292525;1041.07344463428;1043.4521876015076;627.576385939489;636.5893998371071;637.5728262064428;639.808057582728;784.1100351101541;15448.382064717398;15686.372545098182;15696.76454853178;15697.37210511578;15709.293081811345;12387.75224495505;12628.652844062313;12676.429560669792;12690.619281421572;12879;8561.14388561139;10217.540210690537;10292.897071029269;10625.043746062891;10642;3484.6515348465205;3490.965090349089;3507.859685612598;3564.7326450516057;3635.7636753611196;80464;81576;81908.59045704793;84502.57748711217;85185.57407212924;9682.177014953808;9740.561674724655;9775.65785197529;9875.506224688737;9989;21662.241821536485;21937.232196873032;21937.670934935988;21999.34001979943;22029.779702202773;6688.933110668929;7456.105267367951;7580.204078571671;7693.1922148173635;7888.7633370998965;4221.472315960505;4298.76356800379;4319.524852266248;4333.58830911063;4370.125974805052;3633.2733453309443;3680.337539242962;3681.889543313705;3689.9815500922323;3696.482492451059;633.4488994574749;647.1457675867904;651.501601275025;652.1782553981922;661.0810972747885;595.6485673452684;604.6070054464545;618.1500436899264;625.4683519008806;628.3496581038603;8220.794480138044;8497.532635704973;8512.361572882059;8533.961633113493;8572.014218364968;588.5879884081074;612.886615976037;627.4603840696997;631.7409861956576;641.887669657808;3720.2745464634677;3831.3295173344554;4007.0984028593334;4020.577839326887;4175.039740859619;80605;81225.59387203027;81239;82839.3432131366;85299;69618.65190674014;70658.64670676716;72412;72815.63592182111;73881;63354;63555;64906;65672.0298391049;65761.34238657648;7742.791976722777;7977.601119944095;7998.680052797823;8049.557274349732;8343.916560834314;35923.640763592026;42620;43881.12237755291;45794.08411831678;45824.8543786408;65237.67381163064;65765.38290394074;66642.33357666362;67928.6603566979;68702;10721.946390267998;10787.730306742395;10918.836217456903;11018.449077546251;11347.943260283646;11932.642020739391;11965.837563705185;12010.75978480408;12196.75606487883;12688;11302.4913878874;11864.703382415164;11916.344601047025;11957.641270761891;11991.700207494883;10398;11454.743469244682;11537.711557211136;11787;12211.75576488483;10920.2901811382;11122.666320010088;11284.887151128713;11510.482028308914;11528.02011829004;9651.517424128626;9753.951230244087;9852.359596626196;9918.900810992087;9937.602495900082;9758.951205243928;9919.950400247953;9928.702138935843;9964.701058968243;9970.900290996997 09:58:42 INFO - PROCESS | 1940 | __end_tp_report 09:58:42 INFO - PROCESS | 1940 | __start_cc_report 09:58:42 INFO - PROCESS | 1940 | _x_x_mozilla_cycle_collect,1265 09:58:42 INFO - PROCESS | 1940 | __end_cc_report 09:58:42 INFO - PROCESS | 1940 | __startTimestamp1469120322872__endTimestamp 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | ------- Summary: start ------- 09:58:42 INFO - PROCESS | 1940 | Number of tests: 6 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | [#0] dojo.html Cycles:165 Average:5846.67 Median:6151.98 stddev:654.31 (10.6%) stddev-sans-first:628.65 09:58:42 INFO - PROCESS | 1940 | Values: 3446.7 3555.9 3566.5 3571.7 3642.6 5795.0 5825.5 5834.6 5836.9 5839.9 5801.8 5838.7 5846.6 5851.9 5855.3 5232.2 5277.7 5281.0 5323.9 5366.4 5321.2 5370.1 5378.2 5384.3 5394.7 4434.2 5339.1 5351.4 5354.8 5372.0 5343.5 5389.3 5392.8 5395.4 5422.2 5102.5 5278.7 5278.8 5279.3 5300.0 4993.6 5646.3 5700.2 5730.3 5787.5 6315.5 6329.2 6355.0 6367.6 6383.6 6268.2 6305.1 6347.3 6350.5 6367.3 5865.6 5933.7 5962.1 5970.5 5985.6 5292.2 6377.6 6379.5 6387.5 6399.2 6077.6 6124.9 6321.4 6333.2 6345.1 6302.8 6354.6 6362.1 6362.9 6383.4 5294.5 6285.9 6307.1 6342.7 6345.8 6221.8 6318.9 6329.4 6338.0 6356.1 4960.4 4993.2 5004.0 5033.5 5040.9 3796.7 4480.0 4499.4 4500.9 4536.8 5913.1 5929.4 5934.2 5936.1 5955.8 6210.6 6224.5 6305.6 6324.0 6325.9 5935.3 5940.8 5951.7 5987.6 5996.4 5491.6 5506.8 5533.6 5537.0 5552.6 6306.6 6347.5 6411.9 6425.7 6434.9 6289.1 6296.6 6305.9 6321.2 6323.2 6391.5 6404.2 6404.6 6409.9 6416.9 6194.9 6196.0 6286.5 6290.3 6300.3 6242.8 6264.1 6306.9 6311.8 6312.9 6123.7 6259.8 6279.4 6297.9 6336.2 6198.8 6314.3 6326.9 6328.5 6355.5 6119.2 6150.2 6152.5 6171.5 6186.8 6152.0 6211.2 6267.7 6322.1 6328.2 6156.9 6163.4 6170.4 6194.9 6215.1 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | [#1] ext.html Cycles:180 Average:70763.34 Median:18946.83 stddev:157678.21 (832.2%) stddev-sans-first:158032.34 09:58:42 INFO - PROCESS | 1940 | Values: 519.9 529.7 593.4 606.8 621.0 29804.4 33325.0 39201.4 40206.0 40395.2 46635.5 47192.6 47262.0 47271.8 47468.0 9874.7 10085.8 10090.8 10104.5 10124.9 9270.2 12078.5 12372.2 12378.2 12387.1 11928.6 11979.1 11986.4 11995.8 12005.3 12035.3 12162.5 12196.1 12202.9 12204.6 10074.4 11952.9 12069.9 12075.5 12079.8 42528.0 42848.4 42860.1 42965.0 43051.6 3255.9 3269.9 3318.2 3482.8 3638.3 28804.4 29148.6 30107.5 30726.2 30882.7 2810.7 2835.4 2840.2 2872.5 2901.3 496064.0 496994.0 498431.0 499082.0 499301.0 37869.0 38953.0 69135.7 69625.3 69937.3 37417.4 37575.0 37769.2 37769.8 37829.6 16737.4 16895.8 16913.5 16926.7 16929.7 13136.5 13358.1 14280.4 14315.4 14402.4 7189.3 8517.5 8541.1 8541.8 8628.9 3470.5 3520.9 3522.2 3536.3 3558.9 937.2 960.8 1028.7 1044.6 1160.1 28374.1 28697.3 28762.0 28769.4 28838.4 1137.2 1185.2 1236.5 1243.2 1251.7 14702.9 15039.8 15042.8 15339.4 15410.9 645690.0 647649.0 651330.0 653734.0 654028.0 65742.0 66299.4 66305.0 66361.3 66758.3 1385.3 1428.4 1534.9 1543.8 1689.5 29432.7 29909.3 29911.4 30123.2 30631.2 52792.0 52957.2 53533.0 53570.0 53572.7 526049.0 527555.0 527583.0 528966.0 853229.0 27978.2 28734.1 28817.4 28818.1 28822.7 45438.3 47212.8 47420.8 47785.0 49016.5 37670.2 38625.2 38831.8 38832.0 39465.6 38070.6 38083.8 38639.6 38651.6 39028.4 37803.2 38157.0 38164.4 38430.6 38985.0 32773.3 34002.5 34276.0 34327.0 34423.1 24972.6 25334.6 25474.0 25830.5 25875.6 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | [#2] jquery.html Cycles:180 Average:35979.63 Median:21404.79 stddev:104590.93 (488.6%) stddev-sans-first:104850.25 09:58:42 INFO - PROCESS | 1940 | Values: 416.8 422.4 423.4 425.1 429.8 16967.0 19500.5 20876.0 20886.9 20909.0 20852.5 21254.9 21369.7 21401.7 21407.9 5720.2 5821.2 5940.6 5947.5 5955.3 5904.7 5954.0 5977.8 5987.7 5995.6 5728.9 5838.2 5908.8 5917.1 5940.9 4983.3 5814.7 5836.4 5875.9 5885.0 5155.3 5569.3 5870.8 5876.4 5877.4 13739.4 13831.9 13886.7 19713.2 24704.4 2151.7 2271.8 2332.3 2378.9 2446.9 9867.0 9994.4 10089.0 10102.2 10335.9 1544.5 1626.4 1842.2 1887.9 1939.3 119579.0 121129.0 124136.0 124303.4 129877.0 9422.8 11449.8 11671.4 11709.5 11860.8 14962.9 15009.6 15029.2 15050.2 15054.2 8715.7 9035.6 9037.0 9055.5 9143.8 9117.2 9126.6 9133.4 9214.4 9223.7 4643.2 4701.9 4713.2 4756.9 4770.9 1685.4 2039.9 2055.0 2063.6 2084.8 902.2 993.1 997.2 1032.5 1115.3 19844.0 19937.0 19952.1 20125.1 20251.6 966.1 1026.5 1040.7 1049.3 1124.7 8312.5 9224.3 10547.0 10609.0 10612.4 601393.0 609663.0 618714.0 640468.0 655621.0 133021.0 135991.6 138893.0 139062.6 142159.6 1049.7 1061.3 1066.8 1078.1 1079.8 17517.1 17525.7 17655.8 17958.6 18148.6 54585.2 55002.7 55141.0 55520.7 55623.2 75159.7 76848.7 77104.8 78973.6 80546.6 8274.4 8288.1 8303.7 8416.1 8423.8 9542.4 10412.8 12102.2 12558.9 12741.0 10906.0 11060.3 11107.2 11215.5 11229.0 8732.8 10840.8 10972.6 11049.0 11074.7 11088.3 11089.9 11349.4 11428.3 11585.7 8293.6 8293.6 8379.3 8402.4 8454.2 9820.0 10435.4 10532.5 11256.8 11301.0 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | [#3] mootools.html Cycles:180 Average:5934.69 Median:4988.73 stddev:3343.25 (67.0%) stddev-sans-first:3335.25 09:58:42 INFO - PROCESS | 1940 | Values: 1399.7 1446.7 1648.7 1875.5 2038.9 8408.6 9100.4 9229.5 9257.3 9261.5 8976.5 9209.1 9220.4 9237.0 9246.7 2558.2 2598.8 2599.3 2600.5 2601.9 2510.5 2578.3 2580.7 2582.6 2601.3 2554.2 2580.7 2595.4 2602.6 2607.3 2350.7 2400.8 2570.3 2580.2 2587.5 2528.4 2574.6 2578.2 2580.6 2596.6 8922.9 9111.8 9223.7 9302.0 9308.3 5674.1 5854.9 5860.3 5862.7 5869.1 3717.3 3756.8 3822.2 3850.4 3859.2 1055.8 1066.9 1067.4 1079.7 1105.7 12362.7 12586.3 12605.7 12612.7 12615.9 4178.8 4757.3 4876.1 4894.0 4926.7 9785.5 9796.8 9808.2 9817.4 9836.5 5601.0 5642.0 5646.2 5652.5 5661.7 4647.7 4765.1 5099.1 5146.3 5241.0 4058.2 4146.7 4193.6 4215.1 4225.3 1291.0 1307.1 1307.9 1316.5 1318.0 766.7 904.3 916.1 921.5 973.7 6128.8 6238.4 6258.4 6278.3 6280.6 473.5 477.6 497.8 498.0 499.6 3839.1 4024.8 4080.8 4081.7 4138.5 11122.8 11540.8 11623.9 11653.2 11695.6 11423.8 11433.5 11454.1 11492.4 11493.4 8767.9 8767.9 8859.5 8863.0 8868.4 5827.9 5947.8 6009.7 6029.8 6175.0 9885.7 10073.0 10232.6 10238.5 10254.1 8438.5 9935.2 10067.7 10290.1 10384.6 6214.3 7129.9 7314.0 7792.6 7938.8 7182.6 8603.4 8665.8 8667.3 8672.1 7571.0 7727.4 7737.1 7777.2 7817.6 7453.2 7558.3 7613.1 7614.1 7775.7 7277.5 7308.6 7330.0 7334.1 7369.6 4970.8 4976.6 5090.3 5162.2 5190.8 4988.3 4989.2 5016.2 5077.1 5237.6 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | [#4] prototype.html Cycles:180 Average:5864.97 Median:6680.75 stddev:1879.23 (28.1%) stddev-sans-first:1838.08 09:58:42 INFO - PROCESS | 1940 | Values: 334.1 340.9 343.6 352.0 352.9 6659.5 6706.7 6719.8 6772.6 6781.5 6616.7 6645.0 6657.2 6665.4 6696.1 4673.4 4787.4 4797.7 4809.8 4819.2 4768.7 4815.5 4941.4 4968.6 4986.5 4066.9 4746.6 4860.6 4897.6 4911.8 4877.6 4888.2 4894.9 4898.5 4898.6 4019.8 4060.4 4163.1 4169.9 4202.6 6325.5 6361.8 6367.2 6401.8 6408.2 5817.4 5823.4 5834.3 5850.8 5851.0 7068.4 7074.7 7074.7 7074.9 7091.3 4450.7 4732.6 4740.7 4742.3 4744.5 7314.7 7472.6 7514.0 7522.2 7534.1 4718.1 4718.4 4740.1 4741.1 4744.1 4811.7 4811.9 4812.3 4816.7 4816.8 5710.8 5716.7 5719.0 5728.4 5738.9 6407.0 6524.7 6892.7 7107.9 7137.1 1644.3 1664.9 1676.5 1677.4 1684.0 1048.9 1052.0 1069.3 1075.9 1078.2 5505.3 6539.2 6722.2 6723.7 6733.4 7288.1 7382.9 7452.3 7579.6 7583.4 6718.3 6773.9 6809.4 6824.7 6827.5 3414.8 3931.2 3963.7 3963.9 3968.6 7520.1 7770.6 7778.5 7795.9 7801.8 5600.8 6429.8 7662.7 7686.8 7734.5 7761.6 7790.8 7798.1 7801.6 7808.9 7484.3 7503.4 7510.2 7521.1 7526.5 7076.4 7335.4 7345.7 7373.0 7381.6 7747.4 7764.7 7784.5 7791.6 7809.7 4906.8 4978.6 4987.7 4994.5 4997.8 7385.2 7396.4 7402.4 7412.8 7415.2 6956.6 7147.0 7168.0 7287.9 7296.9 7135.1 7198.9 7212.7 7223.6 7229.0 7045.5 7055.0 7065.3 7070.4 7072.5 7218.6 7250.7 7254.4 7261.1 7275.5 6793.4 7013.1 7104.6 7106.1 7121.9 09:58:42 INFO - PROCESS | 1940 | 09:58:42 INFO - PROCESS | 1940 | [#5] yui.html Cycles:180 Average:17709.88 Median:591.89 stddev:24055.32 (4064.2%) stddev-sans-first:24088.48 09:58:42 INFO - PROCESS | 1940 | Values: 595.2 612.0 774.4 791.6 796.0 15107.0 15337.1 15344.4 15344.9 17394.8 16422.9 16433.3 16441.3 16447.6 17368.0 797.7 812.0 962.4 995.3 998.5 796.9 801.0 803.4 984.2 1012.7 1041.7 1044.5 1049.8 1050.2 1050.8 1037.3 1037.3 1040.0 1041.1 1043.5 627.6 636.6 637.6 639.8 784.1 15448.4 15686.4 15696.8 15697.4 15709.3 12387.8 12628.7 12676.4 12690.6 12879.0 8561.1 10217.5 10292.9 10625.0 10642.0 3484.7 3491.0 3507.9 3564.7 3635.8 80464.0 81576.0 81908.6 84502.6 85185.6 9682.2 9740.6 9775.7 9875.5 9989.0 21662.2 21937.2 21937.7 21999.3 22029.8 6688.9 7456.1 7580.2 7693.2 7888.8 4221.5 4298.8 4319.5 4333.6 4370.1 3633.3 3680.3 3681.9 3690.0 3696.5 633.4 647.1 651.5 652.2 661.1 595.6 604.6 618.2 625.5 628.3 8220.8 8497.5 8512.4 8534.0 8572.0 588.6 612.9 627.5 631.7 641.9 3720.3 3831.3 4007.1 4020.6 4175.0 80605.0 81225.6 81239.0 82839.3 85299.0 69618.7 70658.6 72412.0 72815.6 73881.0 63354.0 63555.0 64906.0 65672.0 65761.3 7742.8 7977.6 7998.7 8049.6 8343.9 35923.6 42620.0 43881.1 45794.1 45824.9 65237.7 65765.4 66642.3 67928.7 68702.0 10721.9 10787.7 10918.8 11018.4 11347.9 11932.6 11965.8 12010.8 12196.8 12688.0 11302.5 11864.7 11916.3 11957.6 11991.7 10398.0 11454.7 11537.7 11787.0 12211.8 10920.3 11122.7 11284.9 11510.5 11528.0 9651.5 9754.0 9852.4 9918.9 9937.6 9759.0 9920.0 9928.7 9964.7 9970.9 09:58:42 INFO - PROCESS | 1940 | -------- Summary: end -------- 09:58:42 INFO - PROCESS | 1940 | 09:58:43 INFO - TEST-INFO | 1940: exit 0 09:58:43 INFO - TEST-OK | dromaeo_css | took 1112841ms 09:58:43 INFO - TEST-START | kraken 09:58:43 INFO - operating with platform_type : mac_ 09:58:43 INFO - Initialising browser for kraken test... 09:58:43 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpgtRaxJ/profile http://localhost:50826/getInfo.html) 09:58:45 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 09:58:45 INFO - PROCESS | 1944 | colorDepth:24 09:58:45 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 09:58:45 INFO - PROCESS | 1944 | __metrics 09:58:45 INFO - PROCESS | 1944 | 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" 09:58:45 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:58:46 INFO - TEST-INFO | 1944: exit 0 09:58:46 INFO - Browser initialized. 09:58:46 INFO - Running cycle 1/1 for kraken test... 09:58:46 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpgtRaxJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:58 INFO - PROCESS | 1947 | RSS: Main: 160608256 09:58:58 INFO - PROCESS | 1947 | 09:59:01 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50826/tests/kraken/driver.html?testName=audio-beat-detection) 09:59:01 INFO - PROCESS | 1947 | RSS: Main: 224063488 09:59:01 INFO - PROCESS | 1947 | 09:59:04 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50826/tests/kraken/driver.html?testName=audio-dft) 09:59:05 INFO - PROCESS | 1947 | RSS: Main: 240062464 09:59:05 INFO - PROCESS | 1947 | 09:59:08 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50826/tests/kraken/driver.html?testName=audio-fft) 09:59:08 INFO - PROCESS | 1947 | RSS: Main: 326811648 09:59:08 INFO - PROCESS | 1947 | 09:59:10 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50826/tests/kraken/driver.html?testName=audio-oscillator) 09:59:11 INFO - PROCESS | 1947 | RSS: Main: 231366656 09:59:11 INFO - PROCESS | 1947 | 09:59:13 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50826/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:59:14 INFO - PROCESS | 1947 | RSS: Main: 232648704 09:59:14 INFO - PROCESS | 1947 | 09:59:16 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50826/tests/kraken/driver.html?testName=imaging-darkroom) 09:59:17 INFO - PROCESS | 1947 | RSS: Main: 268230656 09:59:17 INFO - PROCESS | 1947 | 09:59:20 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50826/tests/kraken/driver.html?testName=imaging-desaturate) 09:59:20 INFO - PROCESS | 1947 | RSS: Main: 271355904 09:59:20 INFO - PROCESS | 1947 | 09:59:23 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50826/tests/kraken/driver.html?testName=json-parse-financial) 09:59:24 INFO - PROCESS | 1947 | RSS: Main: 269660160 09:59:24 INFO - PROCESS | 1947 | 09:59:25 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50826/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:59:26 INFO - PROCESS | 1947 | RSS: Main: 260657152 09:59:26 INFO - PROCESS | 1947 | 09:59:28 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:59:29 INFO - PROCESS | 1947 | RSS: Main: 266571776 09:59:29 INFO - PROCESS | 1947 | 09:59:31 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:59:31 INFO - PROCESS | 1947 | RSS: Main: 271093760 09:59:31 INFO - PROCESS | 1947 | 09:59:34 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:59:35 INFO - PROCESS | 1947 | RSS: Main: 278999040 09:59:35 INFO - PROCESS | 1947 | 09:59:38 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:59:38 INFO - PROCESS | 1947 | RSS: Main: 275136512 09:59:38 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:50826/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50826/tests/kraken/driver.html?testName=ai-astar) 09:59:40 INFO - PROCESS | 1947 | RSS: Main: 336269312 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | __start_tp_report 09:59:40 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 09:59:40 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 09:59:40 INFO - PROCESS | 1947 | |i|pagename|runs| 09:59:40 INFO - PROCESS | 1947 | |0;ai-astar;113;114;119;112;112;112;112;113;112;132 09:59:40 INFO - PROCESS | 1947 | |1;audio-beat-detection;156;120;140;162;114;146;92;172;148;108 09:59:40 INFO - PROCESS | 1947 | |2;audio-dft;184;187;186;182;185;183;183;184;183;184 09:59:40 INFO - PROCESS | 1947 | |3;audio-fft;70;78;74;154;55;55;62;68;131;56 09:59:40 INFO - PROCESS | 1947 | |4;audio-oscillator;93;89;136;78;78;96;118;79;77;122 09:59:40 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;87;86;88;85;87;85;88;85;87;84 09:59:40 INFO - PROCESS | 1947 | |6;imaging-darkroom;92;96;92;93;93;93;94;93;92;93 09:59:40 INFO - PROCESS | 1947 | |7;imaging-desaturate;85;86;86;91;85;86;85;97;90;85 09:59:40 INFO - PROCESS | 1947 | |8;json-parse-financial;58;62;64;63;62;62;64;74;64;84 09:59:40 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;60;64;61;74;101;101;56;87;91;101 09:59:40 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;79;79;80;79;79;79;91;102;80;83 09:59:40 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;143;148;137;136;123;122;122;125;146;139 09:59:40 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;161;162;164;210;218;159;165;161;162;160 09:59:40 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;64;65;67;73;88;95;76;67;68;68 09:59:40 INFO - PROCESS | 1947 | __end_tp_report 09:59:40 INFO - PROCESS | 1947 | __start_cc_report 09:59:40 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,1913 09:59:40 INFO - PROCESS | 1947 | __end_cc_report 09:59:40 INFO - PROCESS | 1947 | __startTimestamp1469120380790__endTimestamp 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | ------- Summary: start ------- 09:59:40 INFO - PROCESS | 1947 | Number of tests: 14 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:115.10 Median:113.00 stddev:6.31 (5.6%) stddev-sans-first:6.65 09:59:40 INFO - PROCESS | 1947 | Values: 113.0 114.0 119.0 112.0 112.0 112.0 112.0 113.0 112.0 132.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:152.00 stddev:26.02 (17.1%) stddev-sans-first:26.55 09:59:40 INFO - PROCESS | 1947 | Values: 156.0 120.0 140.0 162.0 114.0 146.0 92.0 172.0 148.0 108.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:1.52 (0.8%) stddev-sans-first:1.62 09:59:40 INFO - PROCESS | 1947 | Values: 184.0 187.0 186.0 182.0 185.0 183.0 183.0 184.0 183.0 184.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:80.30 Median:65.00 stddev:34.17 (52.6%) stddev-sans-first:36.04 09:59:40 INFO - PROCESS | 1947 | Values: 70.0 78.0 74.0 154.0 55.0 55.0 62.0 68.0 131.0 56.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:78.50 stddev:21.38 (27.2%) stddev-sans-first:22.63 09:59:40 INFO - PROCESS | 1947 | Values: 93.0 89.0 136.0 78.0 78.0 96.0 118.0 79.0 77.0 122.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.40 (1.6%) stddev-sans-first:1.45 09:59:40 INFO - PROCESS | 1947 | Values: 87.0 86.0 88.0 85.0 87.0 85.0 88.0 85.0 87.0 84.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:1.20 (1.3%) stddev-sans-first:1.20 09:59:40 INFO - PROCESS | 1947 | Values: 92.0 96.0 92.0 93.0 93.0 93.0 94.0 93.0 92.0 93.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:87.60 Median:86.00 stddev:3.95 (4.6%) stddev-sans-first:4.08 09:59:40 INFO - PROCESS | 1947 | Values: 85.0 86.0 86.0 91.0 85.0 86.0 85.0 97.0 90.0 85.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:64.00 stddev:7.60 (11.9%) stddev-sans-first:7.54 09:59:40 INFO - PROCESS | 1947 | Values: 58.0 62.0 64.0 63.0 62.0 62.0 64.0 74.0 64.0 84.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:62.50 stddev:18.62 (29.8%) stddev-sans-first:18.35 09:59:40 INFO - PROCESS | 1947 | Values: 60.0 64.0 61.0 74.0 101.0 101.0 56.0 87.0 91.0 101.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:79.50 stddev:7.62 (9.6%) stddev-sans-first:7.94 09:59:40 INFO - PROCESS | 1947 | Values: 79.0 79.0 80.0 79.0 79.0 79.0 91.0 102.0 80.0 83.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:134.10 Median:138.00 stddev:10.27 (7.4%) stddev-sans-first:10.37 09:59:40 INFO - PROCESS | 1947 | Values: 143.0 148.0 137.0 136.0 123.0 122.0 122.0 125.0 146.0 139.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:163.00 stddev:22.18 (13.6%) stddev-sans-first:23.15 09:59:40 INFO - PROCESS | 1947 | Values: 161.0 162.0 164.0 210.0 218.0 159.0 165.0 161.0 162.0 160.0 09:59:40 INFO - PROCESS | 1947 | 09:59:40 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:70.50 stddev:10.46 (14.8%) stddev-sans-first:10.56 09:59:40 INFO - PROCESS | 1947 | Values: 64.0 65.0 67.0 73.0 88.0 95.0 76.0 67.0 68.0 68.0 09:59:40 INFO - PROCESS | 1947 | -------- Summary: end -------- 09:59:40 INFO - PROCESS | 1947 | 09:59:41 INFO - TEST-INFO | 1947: exit 0 09:59:41 INFO - TEST-OK | kraken | took 58272ms 09:59:41 INFO - SUITE-END | took 1171s 09:59:41 INFO - Completed test suite (00:19:31) 09:59:41 INFO - javascript benchmark 09:59:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3446.707029902458, 3555.92888142237, 3566.4828599853004, 3571.6964058055064, 3642.562892452906, 5794.971025144873, 5825.5048320892665, 5834.620749651275, 5836.9124463133085, 5839.941600583993, 5801.796937107191, 5838.737256823442, 5846.561507886904, 5851.853703657411, 5855.268091488564, 5232.241325007881, 5277.656952298107, 5280.973595132019, 5323.920141197885, 5366.356037275513, 5321.228421878825, 5370.087085195526, 5378.1663842104535, 5384.326959130109, 5394.730263486829, 4434.201843668138, 5339.119045357511, 5351.384590772072, 5354.759035843377, 5372.0330340538685, 5343.545798607103, 5389.299391079174, 5392.811251606175, 5395.3525576931015, 5422.15956526739, 5102.515261050195, 5278.736063196825, 5278.81524146653, 5279.313689220415, 5300.0, 4993.575546078588, 5646.322441307028, 5700.173474846166, 5730.283714535683, 5787.450192231773, 6315.517275674821, 6329.240491141047, 6354.968225158891, 6367.554271201017, 6383.648899310536, 6268.15379923709, 6305.148804911324, 6347.333529979332, 6350.460210882081, 6367.331430199809, 5865.618734782268, 5933.673647949404, 5962.135490353961, 5970.522358211333, 5985.6408615483215, 5292.2326262691695, 6377.617342959437, 6379.489640828722, 6387.457066149336, 6399.168108146005, 6077.6353418795015, 6124.930239974474, 6321.3994670507145, 6333.1766870306565, 6345.07996340528, 6302.842428939313, 6354.618722876642, 6362.141110949962, 6362.936370636327, 6383.39357761012, 5294.49702278283, 6285.937140628626, 6307.148534947723, 6342.651154186564, 6345.809625711236, 6221.782237621662, 6318.905216421757, 6329.4303512683155, 6337.9683101585115, 6356.110144579762, 4960.429550601655, 4993.201087825931, 5004.0, 5033.521815427529, 5040.924386134211, 3796.677282430969, 4480.0, 4499.437570303712, 4500.909981800346, 4536.79584418702, 5913.142594323799, 5929.436703513159, 5934.198883150806, 5936.079907614327, 5955.76176952917, 6210.565260431817, 6224.533160013018, 6305.558610897193, 6324.0, 6325.9051114233325, 5935.255377146423, 5940.792072277536, 5951.732172052268, 5987.64074155552, 5996.370381110008, 5491.588130890199, 5506.752196151183, 5533.557315414757, 5537.031019571559, 5552.555795536347, 6306.558540902184, 6347.492200623939, 6411.903821442589, 6425.710843012169, 6434.935650643527, 6289.088082228142, 6296.622202667854, 6305.90541141874, 6321.178246828067, 6323.20959880015, 6391.456726178327, 6404.199475065617, 6404.583702059351, 6409.871802563829, 6416.871662566816, 6194.907076393768, 6196.039613859859, 6286.497080233479, 6290.276618188784, 6300.306966233802, 6242.843928901632, 6264.060390941395, 6306.936930630635, 6311.8106456804535, 6312.873742525216, 6123.740204357105, 6259.812205633838, 6279.434850863261, 6297.937020629735, 6336.239651241881, 6198.845028874134, 6314.336994615683, 6326.936730632635, 6328.493720502441, 6355.52333574993, 6119.173911521888, 6150.169727086965, 6152.538559607958, 6171.506279497719, 6186.752529898754, 6151.984922487918, 6211.223597050369, 6267.717952692231, 6322.146510221061, 6328.208973878265, 6156.938430615636, 6163.445289923749, 6170.444659980623, 6194.876102477835, 6215.06773983906], "value": 5805.95060728443, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.8804275016746, 529.7430746088144, 593.368063012891, 606.7694276175052, 621.0280910375262, 29804.40391192175, 33325.0, 39201.41197882034, 40206.0, 40395.19209615806, 46635.53364466354, 47192.58422247338, 47262.0, 47271.81820454482, 47468.0, 9874.703758887244, 10085.798284034316, 10090.848637270446, 10104.494775261244, 10124.898751012506, 9270.165685088341, 12078.516859325617, 12372.195807272543, 12378.195417297848, 12387.132900696955, 11928.642140735792, 11979.101567382437, 11986.400679966011, 11995.760084798343, 12005.27968321899, 12035.277883326984, 12162.51349946001, 12196.14626976112, 12202.877971220263, 12204.633860984184, 10074.395536267848, 11952.940235298855, 12069.939650301692, 12075.516979320817, 12079.758404831855, 42528.0, 42848.357274640905, 42860.14279714388, 42965.0, 43051.56948430538, 3255.9348813023607, 3269.885554005622, 3318.203631128536, 3482.7736197147105, 3638.2541578976247, 28804.42391152165, 29148.562771558445, 30107.548386774215, 30726.23184420385, 30882.69117308843, 2810.676772171206, 2835.4045650413436, 2840.204742672055, 2872.540393537024, 2901.2601786544506, 496064.0, 496994.0, 498431.0, 499082.0, 499301.0, 37869.0, 38953.0, 69135.65432172907, 69625.30374696289, 69937.3006269941, 37417.43873841895, 37575.0, 37769.24461510754, 37769.81115094407, 37829.62170378316, 16737.414190503274, 16895.83104168967, 16913.492595222164, 16926.66146677084, 16929.746053809202, 13136.474541018251, 13358.131721438076, 14280.357383917748, 14315.355808988621, 14402.351894164578, 7189.317014883578, 8517.48895066298, 8541.14588541141, 8541.829163416696, 8628.913710862937, 3470.514128022079, 3520.9471857922154, 3522.1898963238086, 3536.328097661436, 3558.91102722434, 937.2080591899834, 960.7934294126796, 1028.6965345223177, 1044.639599338227, 1160.109022293656, 28374.148775536767, 28697.282567935552, 28762.0, 28769.424611507653, 28838.423231535253, 1137.175547727907, 1185.229600759496, 1236.4744983382027, 1243.2043492164842, 1251.7121062155754, 14702.852971470362, 15039.849601503845, 15042.774358384633, 15339.386424542894, 15410.922945385202, 645690.0, 647649.0, 651330.0, 653734.0, 654028.0, 65742.01386979199, 66299.35904486736, 66305.0, 66361.33638663648, 66758.33241667521, 1385.2658091211674, 1428.428628548589, 1534.9309281082378, 1543.814742230917, 1689.5438231677379, 29432.705672943852, 29909.252268693457, 29911.401771964873, 30123.246918827204, 30631.2342191447, 52792.0, 52957.20564191463, 53533.0, 53570.0, 53572.732136339066, 526049.0, 527555.0, 527583.0, 528966.0, 853229.0, 27978.160655180378, 28734.137975860758, 28817.42365152727, 28818.135455936357, 28822.711772882, 45438.318425222984, 47212.819679506916, 47420.7628961853, 47785.04429911452, 49016.5098349012, 37670.246595067394, 38625.2274954505, 38831.805840970614, 38832.0, 39465.60534394619, 38070.61929380782, 38083.809580951915, 38639.6136038636, 38651.6134838648, 39028.41457378085, 37803.24393512059, 38157.046073848374, 38164.42753358757, 38430.6156938427, 38985.02537436473, 32773.32308782357, 34002.489962650085, 34276.0, 34327.0, 34423.139421514665, 24972.625410618493, 25334.619980699936, 25473.98104075816, 25830.483390332465, 25875.611865822404], "value": 19707.456958815237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.7561976243897, 422.3896469601425, 423.419914716838, 425.10727472308156, 429.8194758201555, 16967.0, 19500.512487187792, 20876.0, 20886.89556552219, 20909.0, 20852.478688032807, 21254.89372553135, 21369.67945480819, 21401.67897481539, 21407.892960535177, 5720.1991721158865, 5821.15593238979, 5940.554458415636, 5947.464728174464, 5955.285365756115, 5904.7047647617665, 5954.0, 5977.790777322794, 5987.730552125143, 5995.640261584319, 5728.942710572904, 5838.21184140142, 5908.822735317947, 5917.112433135043, 5940.910886336708, 4983.277424773406, 5814.680192589406, 5836.358000619928, 5875.9118613220835, 5885.0, 5155.304033955405, 5569.275994120739, 5870.794522191745, 5876.353601103875, 5877.382874798127, 13739.381727822172, 13831.930840345834, 13886.652833679138, 19713.211471541123, 24704.3823904402, 2151.66649169379, 2271.7501074881748, 2332.3236261484317, 2378.940526486834, 2446.8898899549563, 9866.963968783246, 9994.350367226107, 10089.041541053588, 10102.191824654083, 10335.948320258425, 1544.5057581573901, 1626.430749237769, 1842.1986435900426, 1887.9056047197585, 1939.2727727102338, 119579.0, 121129.0, 124135.9999999991, 124303.37848310791, 129877.0, 9422.811543769087, 11449.771004580029, 11671.35807530577, 11709.531618735326, 11860.762784744256, 14962.850371496146, 15009.624759380884, 15029.173395463136, 15050.172240526657, 15054.247287635575, 8715.694950676696, 9035.593398297093, 9037.0, 9055.501947392957, 9143.817123657622, 9117.179453849185, 9126.634934602542, 9133.442636061913, 9214.401063930824, 9223.677171299107, 4643.233866412004, 4701.882452938704, 4713.175194341045, 4756.857294281177, 4770.948492148409, 1685.3595633659377, 2039.94900127498, 2054.9794502054788, 2063.566651003276, 2084.8220755273223, 902.1700035966926, 993.145894530703, 997.1922742578535, 1032.4631191780381, 1115.2694984784816, 19844.0, 19937.0, 19952.10215540304, 20125.09437075335, 20251.594968100555, 966.1160038564634, 1026.4764969865273, 1040.7398150462384, 1049.3284298049255, 1124.696331990358, 8312.54281014538, 9224.262059035262, 10547.0, 10608.992145746142, 10612.416317102634, 601393.0, 609663.0, 618714.0, 640468.0, 655621.0, 133021.0, 135991.6012797111, 138893.0, 139062.60937390497, 142159.5784042167, 1049.7480604654827, 1061.3207547169818, 1066.7919755647729, 1078.1374900080061, 1079.8164312066967, 17517.139373588456, 17525.737113943047, 17655.823441765933, 17958.5510362242, 18148.637027259643, 54585.18122228249, 55002.724986374815, 55141.0, 55520.72239638776, 55623.165652514435, 75159.74520764386, 76848.67862316986, 77104.84342734976, 78973.60513197398, 80546.59726701329, 8274.420790544604, 8288.088310285742, 8303.6678532858, 8416.074231834617, 8423.789405264917, 9542.42745435276, 10412.791744165226, 12102.152849300464, 12558.87441125577, 12741.0, 10905.963933426312, 11060.336379817236, 11107.222494425312, 11215.495302711235, 11229.0, 8732.825343493221, 10840.837387439353, 10972.615958441416, 11049.0, 11074.667759967213, 11088.279261847954, 11089.889101108885, 11349.375784331942, 11428.25716328436, 11585.710357241136, 8293.58532073406, 8293.58532073406, 8379.287760540423, 8402.41183117176, 8454.154584541742, 9819.950900245452, 10435.426051567236, 10532.525446437352, 11256.83114753263, 11301.0], "value": 9591.349563360476, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.6570840144166, 1446.6744982378973, 1648.6537827056318, 1875.502991707198, 2038.9490262743439, 8408.621612027458, 9100.40847344922, 9229.492377919212, 9257.305702072346, 9261.490618016007, 8976.461412315266, 9209.12513311236, 9220.446773193597, 9236.999999999984, 9246.676366327163, 2558.155808583163, 2598.7920966322745, 2599.2981894888367, 2600.5449046416898, 2601.9219423417235, 2510.5104504621604, 2578.2780821370047, 2580.7290234525385, 2582.5609646360163, 2601.349662584354, 2554.246497283306, 2580.7290234525385, 2595.416031392931, 2602.557565213918, 2607.2960300718796, 2350.6826578411865, 2400.8319417640596, 2570.2546261584116, 2580.2388295452793, 2587.4825034993078, 2528.3679080229945, 2574.613807928807, 2578.162097318379, 2580.63871058052, 2596.5975273832587, 8922.86615700765, 9111.81776364469, 9223.723288301295, 9302.023287554777, 9308.34841561091, 5674.148877668342, 5854.941450585482, 5860.296764388258, 5862.70686465675, 5869.090291004901, 3717.3308804415196, 3756.849726010956, 3822.1782316802023, 3850.4224366345275, 3859.2281543691374, 1055.8469021991846, 1066.9359838409644, 1067.3542506783363, 1079.7354648111263, 1105.6682995101519, 12362.690932726664, 12586.307753073577, 12605.684857878443, 12612.747745045048, 12615.927646150089, 4178.8328466860985, 4757.310190022427, 4876.122297986397, 4893.951060489349, 4926.679765815211, 9785.461799601091, 9796.755081122887, 9808.21534277256, 9817.410955342702, 9836.508174591241, 5601.019821531298, 5642.0, 5646.181303710939, 5652.547796176296, 5661.745221465044, 4647.651426143053, 4765.11845308619, 5099.107656160157, 5146.305248791366, 5241.030409374202, 4058.2492238935893, 4146.709730318909, 4193.559676233952, 4215.114825886537, 4225.345071513921, 1290.9612711618665, 1307.0589175793414, 1307.8888294494884, 1316.4997301025548, 1318.0246617502978, 766.7469734987441, 904.3127223310331, 916.1479823763964, 921.5115988526092, 973.7273563402259, 6128.785492507741, 6238.438540531374, 6258.405451482102, 6278.277998030285, 6280.591761535486, 473.5430309751107, 477.59165913144454, 497.7605761653529, 497.9875503112451, 499.5578912662247, 3839.1361943562474, 4024.75851448914, 4080.755154690728, 4081.673466122703, 4138.52406973196, 11122.833157502644, 11540.826887596693, 11623.88376116228, 11653.18427710069, 11695.649130526097, 11423.771524569462, 11433.542658293742, 11454.083673306115, 11492.42537873103, 11493.367864767519, 8767.912320876709, 8767.912320876838, 8859.51272679996, 8862.955685221532, 8868.379213454997, 5827.883442331215, 5947.762089516371, 6009.699515024318, 6029.8191054268445, 6174.969125154345, 9885.654002109892, 10072.992700730161, 10232.64185753495, 10238.488075596339, 10254.077133058061, 8438.451500652438, 9935.15551178129, 10067.748306292402, 10290.073893349634, 10384.636537721144, 6214.25428948529, 7129.928700712927, 7314.0, 7792.649330780242, 7938.761837144895, 7182.6408679564765, 8603.397762156588, 8665.783355416164, 8667.263282620796, 8672.132786721277, 7571.0, 7727.42044346665, 7737.1102323233545, 7777.1833957433655, 7817.570033648204, 7453.217412171643, 7558.281963213486, 7613.08642962848, 7614.086309642878, 7775.727849525239, 7277.454190935595, 7308.5614863108385, 7330.010448589373, 7334.119905611361, 7369.557826530426, 4970.776315065758, 4976.626752993468, 5090.26191202281, 5162.174052151638, 5190.766415511235, 4988.301637770643, 4989.151844186569, 5016.172331565251, 5077.060743762416, 5237.580993520433], "value": 4699.486343094589, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.11459631975265, 340.90284268983345, 343.6477610449289, 351.9718422526198, 352.85709287738445, 6659.533832631711, 6706.6981985810635, 6719.764808231701, 6772.64547090582, 6781.525293229476, 6616.702248398821, 6644.966775166129, 6657.167854018247, 6665.43343815775, 6696.062551242818, 4673.43918729752, 4787.449443314029, 4797.688150270221, 4809.78355973982, 4819.180739274314, 4768.7138771673635, 4815.470298267188, 4941.357623508939, 4968.6025117990475, 4986.4514903360605, 4066.877993660195, 4746.596539294164, 4860.635452341071, 4897.6081913447015, 4911.75441227939, 4877.585405240558, 4888.242322439992, 4894.853154405374, 4898.485659005824, 4898.583620392271, 4019.799010049515, 4060.4112403701592, 4163.084121493267, 4169.895752606179, 4202.579742025803, 6325.525585581054, 6361.777337793202, 6367.204099487564, 6401.8399540011405, 6408.166938297992, 5817.389174136697, 5823.388544202882, 5834.299884013945, 5850.795222167247, 5851.005329094064, 7068.363847253772, 7074.665360431045, 7074.6816393262425, 7074.929250707479, 7091.255418181068, 4450.732956022616, 4732.550407711262, 4740.668153229275, 4742.264948932921, 4744.454387745419, 7314.7439839605895, 7472.626368681545, 7514.0231769870115, 7522.247775222489, 7534.0582427196605, 4718.127146477844, 4718.433787945469, 4740.051989602093, 4741.0754902794315, 4744.146053710365, 4811.711297322172, 4811.927821082687, 4812.25410061441, 4816.710997340171, 4816.8073277069225, 5710.8001219957105, 5716.742746576331, 5719.0, 5728.398518155617, 5738.885222295614, 6407.0, 6524.7276781028195, 6892.655367231719, 7107.92892071083, 7137.1435427749, 1644.2600829626715, 1664.9334026639042, 1676.4970508847541, 1677.3626022111519, 1684.0400971446159, 1048.8846226915034, 1051.9579216831394, 1069.2835800014009, 1075.9462026898623, 1078.223678951154, 5505.3393592768325, 6539.2479864815205, 6722.159730033746, 6723.663816809238, 6733.3939945405145, 7288.08898887639, 7382.852342953111, 7452.254774522505, 7579.583122928181, 7583.355414789694, 6718.328167183243, 6773.9322606774285, 6809.353111454404, 6824.727010919508, 6827.453803695693, 3414.7536149305324, 3931.1744533647684, 3963.7027222958395, 3963.8612648557737, 3968.6428221460205, 7520.1199700074985, 7770.572618505923, 7778.533288002738, 7795.922040779519, 7801.765947021599, 5600.775968961196, 6429.807105786834, 7662.73180438682, 7686.769396918102, 7734.5359278443475, 7761.573113478813, 7790.766277011699, 7798.064232292275, 7801.648925798353, 7808.882866757003, 7484.288992545699, 7503.362214211853, 7510.248975102446, 7521.05986751656, 7526.548407095592, 7076.398506127037, 7335.4498412618195, 7345.742898998509, 7373.004644372941, 7381.630918454163, 7747.3802095833325, 7764.6894124234395, 7784.494007889469, 7791.64937577799, 7809.726659566888, 4906.779194936165, 4978.60171186297, 4987.650864439454, 4994.525520075587, 4997.750112494288, 7385.224551422023, 7396.4082873368925, 7402.370798481974, 7412.777616671508, 7415.2214017527385, 6956.582605043714, 7147.0, 7168.032315637531, 7287.927120728725, 7296.890546641911, 7135.143782746104, 7198.89201661965, 7212.675429605575, 7223.638818059181, 7229.0, 7045.506814522934, 7055.047568578177, 7065.328793764585, 7070.36366726997, 7072.5402848814665, 7218.566885986857, 7250.709971601288, 7254.383377412979, 7261.056062712025, 7275.454340924346, 6793.354631310018, 7013.053237813034, 7104.644767761694, 7106.076210092655, 7121.893171602534], "value": 5237.533085932103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.1845971019702, 611.9724612392439, 774.4114473000525, 791.6121100660678, 796.0328201235498, 15107.018043827158, 15337.079775213468, 15344.386224551032, 15344.923275383622, 17394.826051739477, 16422.917885410556, 16433.342666293363, 16441.342346306134, 16447.58881027975, 17368.04475753833, 797.6609940775177, 812.0296245986049, 962.3696478806393, 995.2635050062974, 998.5356809083775, 796.8844517544952, 800.9599520024004, 803.4255507312271, 984.2372161574801, 1012.6759436980169, 1041.6874937518753, 1044.4621020174577, 1049.7847941172042, 1050.180858930032, 1050.8266136087536, 1037.3153718545761, 1037.3205550364523, 1040.043160292525, 1041.07344463428, 1043.4521876015076, 627.576385939489, 636.5893998371071, 637.5728262064428, 639.808057582728, 784.1100351101541, 15448.382064717398, 15686.372545098182, 15696.76454853178, 15697.37210511578, 15709.293081811345, 12387.75224495505, 12628.652844062313, 12676.429560669792, 12690.619281421572, 12879.0, 8561.14388561139, 10217.540210690537, 10292.897071029269, 10625.043746062891, 10642.0, 3484.6515348465205, 3490.965090349089, 3507.859685612598, 3564.7326450516057, 3635.7636753611196, 80464.0, 81576.0, 81908.59045704793, 84502.57748711217, 85185.57407212924, 9682.177014953808, 9740.561674724655, 9775.65785197529, 9875.506224688737, 9989.0, 21662.241821536485, 21937.232196873032, 21937.670934935988, 21999.34001979943, 22029.779702202773, 6688.933110668929, 7456.105267367951, 7580.204078571671, 7693.1922148173635, 7888.7633370998965, 4221.472315960505, 4298.76356800379, 4319.524852266248, 4333.58830911063, 4370.125974805052, 3633.2733453309443, 3680.337539242962, 3681.889543313705, 3689.9815500922323, 3696.482492451059, 633.4488994574749, 647.1457675867904, 651.501601275025, 652.1782553981922, 661.0810972747885, 595.6485673452684, 604.6070054464545, 618.1500436899264, 625.4683519008806, 628.3496581038603, 8220.794480138044, 8497.532635704973, 8512.361572882059, 8533.961633113493, 8572.014218364968, 588.5879884081074, 612.886615976037, 627.4603840696997, 631.7409861956576, 641.887669657808, 3720.2745464634677, 3831.3295173344554, 4007.0984028593334, 4020.577839326887, 4175.039740859619, 80605.0, 81225.59387203027, 81239.0, 82839.3432131366, 85299.0, 69618.65190674014, 70658.64670676716, 72412.0, 72815.63592182111, 73881.0, 63354.0, 63555.0, 64906.0, 65672.0298391049, 65761.34238657648, 7742.791976722777, 7977.601119944095, 7998.680052797823, 8049.557274349732, 8343.916560834314, 35923.640763592026, 42620.0, 43881.12237755291, 45794.08411831678, 45824.8543786408, 65237.67381163064, 65765.38290394074, 66642.33357666362, 67928.6603566979, 68702.0, 10721.946390267998, 10787.730306742395, 10918.836217456903, 11018.449077546251, 11347.943260283646, 11932.642020739391, 11965.837563705185, 12010.75978480408, 12196.75606487883, 12688.0, 11302.4913878874, 11864.703382415164, 11916.344601047025, 11957.641270761891, 11991.700207494883, 10398.0, 11454.743469244682, 11537.711557211136, 11787.0, 12211.75576488483, 10920.2901811382, 11122.666320010088, 11284.887151128713, 11510.482028308914, 11528.02011829004, 9651.517424128626, 9753.951230244087, 9852.359596626196, 9918.900810992087, 9937.602495900082, 9758.951205243928, 9919.950400247953, 9928.702138935843, 9964.701058968243, 9970.900290996997], "value": 6975.306766784841, "unit": "score"}], "name": "dromaeo_css", "value": 7571.697951316614, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 119.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 132.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 140.0, 162.0, 114.0, 146.0, 92.0, 172.0, 148.0, 108.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 186.0, 182.0, 185.0, 183.0, 183.0, 184.0, 183.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 74.0, 154.0, 55.0, 55.0, 62.0, 68.0, 131.0, 56.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 136.0, 78.0, 78.0, 96.0, 118.0, 79.0, 77.0, 122.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 85.0, 87.0, 85.0, 88.0, 85.0, 87.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 92.0, 93.0, 93.0, 93.0, 94.0, 93.0, 92.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 91.0, 85.0, 86.0, 85.0, 97.0, 90.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 64.0, 63.0, 62.0, 62.0, 64.0, 74.0, 64.0, 84.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 61.0, 74.0, 101.0, 101.0, 56.0, 87.0, 91.0, 101.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 79.0, 79.0, 79.0, 91.0, 102.0, 80.0, 83.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 148.0, 137.0, 136.0, 123.0, 122.0, 122.0, 125.0, 146.0, 139.0], "value": 134.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 164.0, 210.0, 218.0, 159.0, 165.0, 161.0, 162.0, 160.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 67.0, 73.0, 88.0, 95.0, 76.0, 67.0, 68.0, 68.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1486.6, "alertThreshold": 2.0}]} 09:59:42 INFO - Return code: 0 09:59:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:59:42 ERROR - # TBPL SUCCESS # 09:59:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:59:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:59:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:59:42 INFO - 2016-07-21 09:59:42.107 screenresolution[1951:13956] starting screenresolution argv=screenresolution get 09:59:42 INFO - 2016-07-21 09:59:42.110 screenresolution[1951:13956] Display 0: 1600x1200x32@60 09:59:42 INFO - 2016-07-21 09:59:42.116 screenresolution[1952:13959] starting screenresolution argv=screenresolution list 09:59:42 INFO - Available Modes on Display 0 09:59:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:59:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:59:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:59:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:59:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:59:42 INFO - Intel Iris: 09:59:42 INFO - Chipset Model: Intel Iris 09:59:42 INFO - Type: GPU 09:59:42 INFO - Bus: Built-In 09:59:42 INFO - VRAM (Dynamic, Max): 1536 MB 09:59:42 INFO - Vendor: Intel (0x8086) 09:59:42 INFO - Device ID: 0x0a2e 09:59:42 INFO - Revision ID: 0x0009 09:59:42 INFO - Displays: 09:59:42 INFO - Display: 09:59:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:59:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:59:42 INFO - Display Serial Number: 1600x1200 60 09:59:42 INFO - Main Display: Yes 09:59:42 INFO - Mirror: Off 09:59:42 INFO - Online: Yes 09:59:42 INFO - Rotation: Supported 09:59:42 INFO - Return code: 0 09:59:42 INFO - Running post-action listener: _resource_record_post_action 09:59:42 INFO - Running post-run listener: _resource_record_post_run 09:59:43 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 1167872; Write bytes: 491747328; Read time: 14; Write time: 1520 09:59:43 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 185352704; Write bytes: 186087424; Read time: 16552; Write time: 397 09:59:43 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 1167872; Write bytes: 295702528; Read time: 14; Write time: 1086 09:59:43 INFO - Running post-run listener: _upload_blobber_files 09:59:43 INFO - Blob upload gear active. 09:59:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:59:43 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 09:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:59:43 INFO - (blobuploader) - INFO - Open directory for files ... 09:59:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:59:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:59:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:45 INFO - (blobuploader) - INFO - Done attempting. 09:59:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:59:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:59:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:46 INFO - (blobuploader) - INFO - Done attempting. 09:59:46 INFO - (blobuploader) - INFO - Iteration through files over. 09:59:46 INFO - Return code: 0 09:59:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:59:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:59:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3281812bc9907429b607903ced159d44dd99fada901a326f9a9e2f8a28fd6101a80883cfababd861fd5037045064f098161dd86dbb8aec19249ea851db69cd84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/097735433bd45a31e74997ee87b1e4f2135e30578fea64dfd3c2e44729e445ee37b1f6f5f66f97c250c29cdee39c42642a6e8bd756883091d16615e5ba8e8b12"} 09:59:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:59:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:59:46 INFO - Contents: 09:59:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3281812bc9907429b607903ced159d44dd99fada901a326f9a9e2f8a28fd6101a80883cfababd861fd5037045064f098161dd86dbb8aec19249ea851db69cd84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/097735433bd45a31e74997ee87b1e4f2135e30578fea64dfd3c2e44729e445ee37b1f6f5f66f97c250c29cdee39c42642a6e8bd756883091d16615e5ba8e8b12"} 09:59:46 INFO - Running post-run listener: copy_logs_to_upload_dir 09:59:46 INFO - Copying logs to upload dir... 09:59:46 INFO - mkdir: /builds/slave/test/build/upload/logs 09:59:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.769017 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-07-21 09:59:46.803751) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-21 09:59:46.807199) ========= 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.5Ix713TpiM/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.K3hfelPw3Z/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3281812bc9907429b607903ced159d44dd99fada901a326f9a9e2f8a28fd6101a80883cfababd861fd5037045064f098161dd86dbb8aec19249ea851db69cd84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/097735433bd45a31e74997ee87b1e4f2135e30578fea64dfd3c2e44729e445ee37b1f6f5f66f97c250c29cdee39c42642a6e8bd756883091d16615e5ba8e8b12"} build_url:https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/b0mJpSlqS2-zMNUyZf96eg/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3281812bc9907429b607903ced159d44dd99fada901a326f9a9e2f8a28fd6101a80883cfababd861fd5037045064f098161dd86dbb8aec19249ea851db69cd84", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/097735433bd45a31e74997ee87b1e4f2135e30578fea64dfd3c2e44729e445ee37b1f6f5f66f97c250c29cdee39c42642a6e8bd756883091d16615e5ba8e8b12"}' ========= master_lag: 1.16 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-21 09:59:47.981496) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-07-21 09:59:47.981815) ========= 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.5Ix713TpiM/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.K3hfelPw3Z/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.005078 ========= master_lag: 2.68 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-07-21 09:59:50.668776) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 09:59:50.669120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 09:59:50.670447) ========= ========= Total master_lag: 4.36 =========