builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0116 starttime: 1460713144.04 results: success (0) buildid: 20160415014444 builduid: 53cbf33aba3b4b43a8b31ea0e7abbaf5 revision: d0c2f09a6285ad7a9f25fcb704de38c9c5168369 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.041126) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.041519) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.041796) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.097527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.097834) ========= 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.f49zopnQlP/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.fPbTyEq5bi/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-15 02:39:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 101M=0s 2016-04-15 02:39:04 (101 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117422 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.235004) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.235316) ========= 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.f49zopnQlP/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.fPbTyEq5bi/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.027052 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.283572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.283908) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d0c2f09a6285ad7a9f25fcb704de38c9c5168369 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d0c2f09a6285ad7a9f25fcb704de38c9c5168369 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f49zopnQlP/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.fPbTyEq5bi/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-15 02:39:04,372 truncating revision to first 12 chars 2016-04-15 02:39:04,372 Setting DEBUG logging. 2016-04-15 02:39:04,372 attempt 1/10 2016-04-15 02:39:04,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d0c2f09a6285?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 02:39:04,645 unpacking tar archive at: fx-team-d0c2f09a6285/testing/mozharness/ program finished with exit code 0 elapsedTime=0.511462 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.813536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.813836) ========= script_repo_revision: d0c2f09a6285ad7a9f25fcb704de38c9c5168369 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.817276) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.817541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 02:39:04.832416) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-15 02:39:04.832723) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f49zopnQlP/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.fPbTyEq5bi/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:05 INFO - MultiFileLogger online at 20160415 02:39:05 in /builds/slave/test 02:39:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:39:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:39:05 INFO - {'append_to_log': False, 02:39:05 INFO - 'base_work_dir': '/builds/slave/test', 02:39:05 INFO - 'blob_upload_branch': 'Fx-Team', 02:39:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:39:05 INFO - 'branch': 'Fx-Team', 02:39:05 INFO - 'buildbot_json_path': 'buildprops.json', 02:39:05 INFO - 'config_files': ('talos/mac_config.py',), 02:39:05 INFO - 'default_actions': ('clobber', 02:39:05 INFO - 'read-buildbot-config', 02:39:05 INFO - 'download-and-extract', 02:39:05 INFO - 'populate-webroot', 02:39:05 INFO - 'create-virtualenv', 02:39:05 INFO - 'install', 02:39:05 INFO - 'run-tests'), 02:39:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:39:05 INFO - 'download_minidump_stackwalk': True, 02:39:05 INFO - 'download_symbols': 'ondemand', 02:39:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:39:05 INFO - 'tooltool.py': '/tools/tooltool.py', 02:39:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:39:05 INFO - '/tools/misc-python/virtualenv.py')}, 02:39:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:39:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:39:05 INFO - 'installer_path': 'installer.exe', 02:39:05 INFO - 'log_level': 'info', 02:39:05 INFO - 'log_name': 'talos', 02:39:05 INFO - 'log_to_console': True, 02:39:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:39:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:39:05 INFO - 'opt_config_files': (), 02:39:05 INFO - 'pip_index': False, 02:39:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:39:05 INFO - 'cmd': ('bash', 02:39:05 INFO - '-c', 02:39:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:39:05 INFO - 'enabled': True, 02:39:05 INFO - 'halt_on_failure': False, 02:39:05 INFO - 'name': 'check_screen_resolution'},), 02:39:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:39:05 INFO - 'cmd': ('bash', 02:39:05 INFO - '-c', 02:39:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:39:05 INFO - 'enabled': True, 02:39:05 INFO - 'halt_on_failure': False, 02:39:05 INFO - 'name': 'check_screen_resolution'},), 02:39:05 INFO - 'run_cmd_checks_enabled': True, 02:39:05 INFO - 'sps_profile': False, 02:39:05 INFO - 'sps_profile_interval': 0, 02:39:05 INFO - 'suite': 'dromaeojs', 02:39:05 INFO - 'system_bits': '32', 02:39:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:39:05 INFO - 'title': 't-yosemite-r7-0116', 02:39:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:39:05 INFO - 'use_talos_json': True, 02:39:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:39:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:39:05 INFO - 'work_dir': 'build'} 02:39:05 INFO - ##### 02:39:05 INFO - ##### Running clobber step. 02:39:05 INFO - ##### 02:39:05 INFO - Running pre-action listener: _resource_record_pre_action 02:39:05 INFO - Running main action method: clobber 02:39:05 INFO - rmtree: /builds/slave/test/build 02:39:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:39:06 INFO - Running post-action listener: _resource_record_post_action 02:39:06 INFO - ##### 02:39:06 INFO - ##### Running read-buildbot-config step. 02:39:06 INFO - ##### 02:39:06 INFO - Running pre-action listener: _resource_record_pre_action 02:39:06 INFO - Running main action method: read_buildbot_config 02:39:06 INFO - Using buildbot properties: 02:39:06 INFO - { 02:39:06 INFO - "project": "", 02:39:06 INFO - "product": "firefox", 02:39:06 INFO - "script_repo_revision": "production", 02:39:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:39:06 INFO - "repository": "", 02:39:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:39:06 INFO - "buildid": "20160415014444", 02:39:06 INFO - "pgo_build": "False", 02:39:06 INFO - "basedir": "/builds/slave/test", 02:39:06 INFO - "buildnumber": 727, 02:39:06 INFO - "slavename": "t-yosemite-r7-0116", 02:39:06 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:39:06 INFO - "platform": "yosemite_r7", 02:39:06 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:39:06 INFO - "branch": "fx-team", 02:39:06 INFO - "repo_path": "integration/fx-team", 02:39:06 INFO - "revision": "d0c2f09a6285ad7a9f25fcb704de38c9c5168369", 02:39:06 INFO - "stage_platform": "macosx64", 02:39:06 INFO - "builduid": "53cbf33aba3b4b43a8b31ea0e7abbaf5", 02:39:06 INFO - "slavebuilddir": "test" 02:39:06 INFO - } 02:39:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 02:39:06 INFO - Running post-action listener: _resource_record_post_action 02:39:06 INFO - ##### 02:39:06 INFO - ##### Running download-and-extract step. 02:39:06 INFO - ##### 02:39:06 INFO - Running pre-action listener: _resource_record_pre_action 02:39:06 INFO - Running main action method: download_and_extract 02:39:06 INFO - mkdir: /builds/slave/test/build/tests 02:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:39:06 INFO - https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:39:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 02:39:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 02:39:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 02:39:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 02:39:08 INFO - Downloaded 1199 bytes. 02:39:08 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 02:39:08 INFO - Using the following test package requirements: 02:39:08 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 02:39:08 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 02:39:08 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 02:39:08 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'jsshell-mac.zip'], 02:39:08 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 02:39:08 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 02:39:08 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 02:39:08 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 02:39:08 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 02:39:08 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 02:39:08 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 02:39:08 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:08 INFO - https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:39:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 02:39:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 02:39:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 02:39:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:39:09 INFO - Downloaded 18031150 bytes. 02:39:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:39:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:39:12 INFO - Return code: 0 02:39:12 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:12 INFO - https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:39:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 02:39:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 02:39:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 02:39:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:39:13 INFO - Downloaded 18031150 bytes. 02:39:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:39:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:39:17 INFO - Return code: 0 02:39:17 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')]} 02:39:17 INFO - https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:39:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 02:39:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 02:39:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 02:39:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:39:18 INFO - Downloaded 10841076 bytes. 02:39:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:39:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:39:18 INFO - Return code: 0 02:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:18 INFO - https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:39:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 02:39:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 02:39:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 02:39:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 02:39:21 INFO - Downloaded 109830796 bytes. 02:39:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 02:39:21 INFO - mkdir: /builds/slave/test/properties 02:39:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:39:21 INFO - Writing to file /builds/slave/test/properties/build_url 02:39:21 INFO - Contents: 02:39:21 INFO - build_url:https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 02:39:22 INFO - Running post-action listener: _resource_record_post_action 02:39:22 INFO - Running post-action listener: set_extra_try_arguments 02:39:22 INFO - ##### 02:39:22 INFO - ##### Running populate-webroot step. 02:39:22 INFO - ##### 02:39:22 INFO - Running pre-action listener: _resource_record_pre_action 02:39:22 INFO - Running main action method: populate_webroot 02:39:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:39:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:39:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:39:22 INFO - '--tppagecycles', 02:39:22 INFO - '7'], 02:39:22 INFO - 'tests': ['tsvgm']}}, 02:39:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:39:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:39:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:39:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:39:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:39:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:39:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['damp', 'tps']}, 02:39:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'tests': ['damp', 'tps']}, 02:39:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['dromaeo_dom']}, 02:39:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:39:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['a11yr', 02:39:22 INFO - 'ts_paint', 02:39:22 INFO - 'tpaint', 02:39:22 INFO - 'sessionrestore', 02:39:22 INFO - 'sessionrestore_no_auto_restore', 02:39:22 INFO - 'tabpaint']}, 02:39:22 INFO - 'other-e10s': {'tests': ['a11yr', 02:39:22 INFO - 'ts_paint', 02:39:22 INFO - 'tpaint', 02:39:22 INFO - 'sessionrestore', 02:39:22 INFO - 'sessionrestore_no_auto_restore', 02:39:22 INFO - 'tabpaint']}, 02:39:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:39:22 INFO - 'ts_paint', 02:39:22 INFO - 'tpaint', 02:39:22 INFO - 'sessionrestore', 02:39:22 INFO - 'sessionrestore_no_auto_restore', 02:39:22 INFO - 'tabpaint']}, 02:39:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:39:22 INFO - 'ts_paint', 02:39:22 INFO - 'tpaint', 02:39:22 INFO - 'sessionrestore', 02:39:22 INFO - 'sessionrestore_no_auto_restore', 02:39:22 INFO - 'tabpaint']}, 02:39:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['a11yr', 02:39:22 INFO - 'ts_paint', 02:39:22 INFO - 'tpaint', 02:39:22 INFO - 'sessionrestore', 02:39:22 INFO - 'sessionrestore_no_auto_restore', 02:39:22 INFO - 'tabpaint']}, 02:39:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['a11yr', 02:39:22 INFO - 'ts_paint', 02:39:22 INFO - 'tpaint', 02:39:22 INFO - 'sessionrestore', 02:39:22 INFO - 'sessionrestore_no_auto_restore', 02:39:22 INFO - 'tabpaint']}, 02:39:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['tsvgx', 02:39:22 INFO - 'tsvgr_opacity', 02:39:22 INFO - 'tart', 02:39:22 INFO - 'tscrollx', 02:39:22 INFO - 'cart']}, 02:39:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:39:22 INFO - 'tsvgr_opacity', 02:39:22 INFO - 'tart', 02:39:22 INFO - 'tscrollx', 02:39:22 INFO - 'cart']}, 02:39:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'talos_options': ['--disable-e10s'], 02:39:22 INFO - 'tests': ['tp5o']}, 02:39:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'tests': ['tp5o']}, 02:39:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'talos_options': ['--disable-e10s', 02:39:22 INFO - '--xperf_path', 02:39:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:39:22 INFO - 'tests': ['tp5n']}, 02:39:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:39:22 INFO - 'talos_options': ['--xperf_path', 02:39:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:39:22 INFO - 'tests': ['tp5n']}}, 02:39:22 INFO - 'talos.zip': {'path': '', 02:39:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:39:22 INFO - Running post-action listener: _resource_record_post_action 02:39:22 INFO - ##### 02:39:22 INFO - ##### Running create-virtualenv step. 02:39:22 INFO - ##### 02:39:22 INFO - Running pre-action listener: _resource_record_pre_action 02:39:22 INFO - Running main action method: create_virtualenv 02:39:22 INFO - Creating virtualenv /builds/slave/test/build/venv 02:39:22 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 02:39:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:39:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:39:22 INFO - Using real prefix '/tools/python27' 02:39:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:39:23 INFO - Installing distribute.............................................................................................................................................................................................done. 02:39:26 INFO - Installing pip.................done. 02:39:26 INFO - Return code: 0 02:39:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:39:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:26 INFO - 'HOME': '/Users/cltbld', 02:39:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:26 INFO - 'LOGNAME': 'cltbld', 02:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:26 INFO - 'MOZ_NO_REMOTE': '1', 02:39:26 INFO - 'NO_EM_RESTART': '1', 02:39:26 INFO - 'PAGER': '/bin/cat', 02:39:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:26 INFO - 'PWD': '/builds/slave/test', 02:39:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:26 INFO - 'SHELL': '/bin/bash', 02:39:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:26 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:26 INFO - 'USER': 'cltbld', 02:39:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:26 INFO - 'XPC_FLAGS': '0x0', 02:39:26 INFO - 'XPC_SERVICE_NAME': '0', 02:39:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:26 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 02:39:26 INFO - Cleaning up... 02:39:26 INFO - Return code: 0 02:39:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:39:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:26 INFO - 'HOME': '/Users/cltbld', 02:39:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:26 INFO - 'LOGNAME': 'cltbld', 02:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:26 INFO - 'MOZ_NO_REMOTE': '1', 02:39:26 INFO - 'NO_EM_RESTART': '1', 02:39:26 INFO - 'PAGER': '/bin/cat', 02:39:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:26 INFO - 'PWD': '/builds/slave/test', 02:39:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:26 INFO - 'SHELL': '/bin/bash', 02:39:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:26 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:26 INFO - 'USER': 'cltbld', 02:39:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:26 INFO - 'XPC_FLAGS': '0x0', 02:39:26 INFO - 'XPC_SERVICE_NAME': '0', 02:39:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:27 INFO - Downloading/unpacking psutil>=0.7.1 02:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:39:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:39:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:39:29 INFO - Installing collected packages: psutil 02:39:29 INFO - Running setup.py install for psutil 02:39:30 INFO - building 'psutil._psutil_osx' extension 02:39:30 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 02:39:30 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 02:39:30 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 02:39:30 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 02:39:30 INFO - building 'psutil._psutil_posix' extension 02:39:30 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 02:39:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:39:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:39:30 INFO - ^ 02:39:30 INFO - 1 warning generated. 02:39:30 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 02:39:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:39:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:39:31 INFO - Successfully installed psutil 02:39:31 INFO - Cleaning up... 02:39:31 INFO - Return code: 0 02:39:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:31 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 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:31 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 02:39:31 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 02:39:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:31 INFO - 'HOME': '/Users/cltbld', 02:39:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:31 INFO - 'LOGNAME': 'cltbld', 02:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:31 INFO - 'MOZ_NO_REMOTE': '1', 02:39:31 INFO - 'NO_EM_RESTART': '1', 02:39:31 INFO - 'PAGER': '/bin/cat', 02:39:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:31 INFO - 'PWD': '/builds/slave/test', 02:39:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:31 INFO - 'SHELL': '/bin/bash', 02:39:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:31 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:31 INFO - 'USER': 'cltbld', 02:39:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:31 INFO - 'XPC_FLAGS': '0x0', 02:39:31 INFO - 'XPC_SERVICE_NAME': '0', 02:39:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:39:31 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 02:39:31 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 02:39:31 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 02:39:31 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 02:39:31 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 02:39:31 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 02:39:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:39:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:39:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:39:33 INFO - Installing collected packages: mozsystemmonitor 02:39:33 INFO - Running setup.py install for mozsystemmonitor 02:39:34 INFO - Successfully installed mozsystemmonitor 02:39:34 INFO - Cleaning up... 02:39:34 INFO - Return code: 0 02:39:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:34 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 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:34 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 02:39:34 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 02:39:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:34 INFO - 'HOME': '/Users/cltbld', 02:39:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:34 INFO - 'LOGNAME': 'cltbld', 02:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:34 INFO - 'MOZ_NO_REMOTE': '1', 02:39:34 INFO - 'NO_EM_RESTART': '1', 02:39:34 INFO - 'PAGER': '/bin/cat', 02:39:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:34 INFO - 'PWD': '/builds/slave/test', 02:39:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:34 INFO - 'SHELL': '/bin/bash', 02:39:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:34 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:34 INFO - 'USER': 'cltbld', 02:39:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:34 INFO - 'XPC_FLAGS': '0x0', 02:39:34 INFO - 'XPC_SERVICE_NAME': '0', 02:39:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:34 INFO - Downloading/unpacking blobuploader==1.2.4 02: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 02: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 02: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 02: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 02: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 02: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 02:39:36 INFO - Downloading blobuploader-1.2.4.tar.gz 02:39:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:39:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:39:36 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 02:39:36 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 02:39:36 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 02:39:36 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 02:39:36 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 02:39:36 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 02:39:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:39:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:39:37 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 02:39:37 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 02:39:37 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 02:39:37 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 02:39:37 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 02:39:37 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 02:39:37 INFO - Downloading docopt-0.6.1.tar.gz 02:39:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:39:38 INFO - Installing collected packages: blobuploader, requests, docopt 02:39:38 INFO - Running setup.py install for blobuploader 02:39:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:39:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:39:38 INFO - Running setup.py install for requests 02:39:38 INFO - Running setup.py install for docopt 02:39:38 INFO - Successfully installed blobuploader requests docopt 02:39:38 INFO - Cleaning up... 02:39:38 INFO - Return code: 0 02:39:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02: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')]} 02:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02: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')]} 02:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:38 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 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:38 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 02:39:38 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 02:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:38 INFO - 'HOME': '/Users/cltbld', 02:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:38 INFO - 'LOGNAME': 'cltbld', 02:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:38 INFO - 'MOZ_NO_REMOTE': '1', 02:39:38 INFO - 'NO_EM_RESTART': '1', 02:39:38 INFO - 'PAGER': '/bin/cat', 02:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:38 INFO - 'PWD': '/builds/slave/test', 02:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:38 INFO - 'SHELL': '/bin/bash', 02:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:38 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:38 INFO - 'USER': 'cltbld', 02:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:38 INFO - 'XPC_FLAGS': '0x0', 02:39:38 INFO - 'XPC_SERVICE_NAME': '0', 02:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-YuKiR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-RcV4HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-U_Tel8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-X0_e9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-CyLhvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-IMgmqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-VjFSOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-2U5jY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:39:39 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-oOP7ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-pmdonf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-GmN2wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-izwW9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-OANcNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-64utjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-RwVNhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-DWforF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:39:40 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-zy969O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:39:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:39:40 INFO - Running setup.py install for manifestparser 02:39:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:39:41 INFO - Running setup.py install for mozcrash 02:39:41 INFO - Running setup.py install for mozdebug 02:39:41 INFO - Running setup.py install for mozdevice 02:39:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:39:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:39:41 INFO - Running setup.py install for mozfile 02:39:41 INFO - Running setup.py install for mozhttpd 02:39:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:39:41 INFO - Running setup.py install for mozinfo 02:39:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:39:41 INFO - Running setup.py install for mozInstall 02:39:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Running setup.py install for mozleak 02:39:42 INFO - Running setup.py install for mozlog 02:39:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Running setup.py install for moznetwork 02:39:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Running setup.py install for mozprocess 02:39:42 INFO - Running setup.py install for mozprofile 02:39:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:39:42 INFO - Running setup.py install for mozrunner 02:39:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:39:43 INFO - Running setup.py install for mozscreenshot 02:39:43 INFO - Running setup.py install for moztest 02:39:43 INFO - Running setup.py install for mozversion 02:39:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:39:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:39:43 INFO - Cleaning up... 02:39:43 INFO - Return code: 0 02:39:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:39:43 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')]} 02:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:43 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')]} 02:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:43 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 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:43 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 02:39:43 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 02:39:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:43 INFO - 'HOME': '/Users/cltbld', 02:39:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:43 INFO - 'LOGNAME': 'cltbld', 02:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:43 INFO - 'MOZ_NO_REMOTE': '1', 02:39:43 INFO - 'NO_EM_RESTART': '1', 02:39:43 INFO - 'PAGER': '/bin/cat', 02:39:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:43 INFO - 'PWD': '/builds/slave/test', 02:39:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:43 INFO - 'SHELL': '/bin/bash', 02:39:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:43 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:43 INFO - 'USER': 'cltbld', 02:39:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:43 INFO - 'XPC_FLAGS': '0x0', 02:39:43 INFO - 'XPC_SERVICE_NAME': '0', 02:39:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:39:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-DZEnma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:39:43 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)) 02:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:39:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-kbzaCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:39:43 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)) 02:39:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:39:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-A7f_gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-o171tO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-bsY9jP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-i7KdEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-zxY5kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-nsppNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-2OAF6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-JYl5pL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-qLDAlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:39:44 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)) 02:39:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:39:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-xMGFUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:39:45 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)) 02:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:39:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-i1wusH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:39:45 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)) 02:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:39:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ZGevCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:39:45 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)) 02:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:39:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-9UmkMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:39:45 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)) 02:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:39:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-xBaflB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:39:45 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)) 02:39:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:39:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-NFa1p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:39:45 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)) 02:39:45 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)) 02:39:45 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)) 02:39:45 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)) 02:39:45 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)) 02:39:45 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)) 02:39:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02: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 02: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 02: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 02: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 02: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 02: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 02:39:47 INFO - Downloading blessings-1.6.tar.gz 02:39:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:39:48 INFO - Installing collected packages: blessings 02:39:48 INFO - Running setup.py install for blessings 02:39:48 INFO - Successfully installed blessings 02:39:48 INFO - Cleaning up... 02:39:48 INFO - Return code: 0 02:39:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:39:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:39:48 INFO - Reading from file tmpfile_stdout 02:39:48 INFO - Current package versions: 02:39:48 INFO - blessings == 1.6 02:39:48 INFO - blobuploader == 1.2.4 02:39:48 INFO - docopt == 0.6.1 02:39:48 INFO - manifestparser == 1.1 02:39:48 INFO - mozInstall == 1.12 02:39:48 INFO - mozcrash == 0.17 02:39:48 INFO - mozdebug == 0.1 02:39:48 INFO - mozdevice == 0.48 02:39:48 INFO - mozfile == 1.2 02:39:48 INFO - mozhttpd == 0.7 02:39:48 INFO - mozinfo == 0.9 02:39:48 INFO - mozleak == 0.1 02:39:48 INFO - mozlog == 3.1 02:39:48 INFO - moznetwork == 0.27 02:39:48 INFO - mozprocess == 0.22 02:39:48 INFO - mozprofile == 0.28 02:39:48 INFO - mozrunner == 6.11 02:39:48 INFO - mozscreenshot == 0.1 02:39:48 INFO - mozsystemmonitor == 0.0 02:39:48 INFO - moztest == 0.7 02:39:48 INFO - mozversion == 1.4 02:39:48 INFO - psutil == 3.1.1 02:39:48 INFO - requests == 1.2.3 02:39:48 INFO - wsgiref == 0.1.2 02:39:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 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 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:48 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 02:39:48 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 02:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:48 INFO - 'HOME': '/Users/cltbld', 02:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:48 INFO - 'LOGNAME': 'cltbld', 02:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:48 INFO - 'MOZ_NO_REMOTE': '1', 02:39:48 INFO - 'NO_EM_RESTART': '1', 02:39:48 INFO - 'PAGER': '/bin/cat', 02:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:48 INFO - 'PWD': '/builds/slave/test', 02:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:48 INFO - 'SHELL': '/bin/bash', 02:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:48 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:48 INFO - 'USER': 'cltbld', 02:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:48 INFO - 'XPC_FLAGS': '0x0', 02:39:48 INFO - 'XPC_SERVICE_NAME': '0', 02:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 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)) 02:39:48 INFO - Cleaning up... 02:39:48 INFO - Return code: 0 02:39:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:39:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:48 INFO - 'HOME': '/Users/cltbld', 02:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:48 INFO - 'LOGNAME': 'cltbld', 02:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:48 INFO - 'MOZ_NO_REMOTE': '1', 02:39:48 INFO - 'NO_EM_RESTART': '1', 02:39:48 INFO - 'PAGER': '/bin/cat', 02:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:48 INFO - 'PWD': '/builds/slave/test', 02:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:48 INFO - 'SHELL': '/bin/bash', 02:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:48 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:48 INFO - 'USER': 'cltbld', 02:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:48 INFO - 'XPC_FLAGS': '0x0', 02:39:48 INFO - 'XPC_SERVICE_NAME': '0', 02:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:49 INFO - Downloading/unpacking jsonschema 02:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:39:51 INFO - Downloading/unpacking functools32 (from jsonschema) 02:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:52 INFO - Downloading functools32-3.2.3-2.tar.gz 02:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:39:52 INFO - warning: no files found matching '*.txt' 02:39:52 INFO - no previously-included directories found matching 'build' 02:39:52 INFO - no previously-included directories found matching 'dist' 02:39:52 INFO - no previously-included directories found matching '.git*' 02:39:52 INFO - Installing collected packages: jsonschema, functools32 02:39:52 INFO - Running setup.py install for functools32 02:39:52 INFO - warning: no files found matching '*.txt' 02:39:52 INFO - no previously-included directories found matching 'build' 02:39:52 INFO - no previously-included directories found matching 'dist' 02:39:52 INFO - no previously-included directories found matching '.git*' 02:39:52 INFO - Successfully installed jsonschema functools32 02:39:52 INFO - Cleaning up... 02:39:52 INFO - Return code: 0 02:39:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:39:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:39:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:39:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:39:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a292a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2a2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3fb54abe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, '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.f49zopnQlP/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.fPbTyEq5bi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 02:39:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:39:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:39:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:39:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:39:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:39:52 INFO - 'HOME': '/Users/cltbld', 02:39:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:39:52 INFO - 'LOGNAME': 'cltbld', 02:39:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:39:52 INFO - 'MOZ_NO_REMOTE': '1', 02:39:52 INFO - 'NO_EM_RESTART': '1', 02:39:52 INFO - 'PAGER': '/bin/cat', 02:39:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:39:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:39:52 INFO - 'PWD': '/builds/slave/test', 02:39:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:39:52 INFO - 'SHELL': '/bin/bash', 02:39:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:39:52 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:39:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:39:52 INFO - 'USER': 'cltbld', 02:39:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:39:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:39:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:39:52 INFO - 'XPC_FLAGS': '0x0', 02:39:52 INFO - 'XPC_SERVICE_NAME': '0', 02:39:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:39:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:39:52 INFO - Downloading/unpacking flake8 02:39:52 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 02:39:52 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 02:39:52 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 02:39:52 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 02:39:52 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 02:39:52 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 02:39:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:39:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:39:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:39:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:39:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:39:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:39:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:39:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:39:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:39:56 INFO - no previously-included directories found matching 'docs/_build' 02:39:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:39:56 INFO - Running setup.py install for pep8 02:39:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:39:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:39:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:39:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:39:56 INFO - no previously-included directories found matching 'docs/_build' 02:39:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:39:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:39:56 INFO - Cleaning up... 02:39:56 INFO - Return code: 0 02:39:56 INFO - Running post-action listener: _resource_record_post_action 02:39:56 INFO - Running post-action listener: _start_resource_monitoring 02:39:56 INFO - Starting resource monitoring. 02:39:56 INFO - ##### 02:39:56 INFO - ##### Running install step. 02:39:56 INFO - ##### 02:39:56 INFO - Running pre-action listener: _resource_record_pre_action 02:39:56 INFO - Running main action method: install 02:39:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:39:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:39:57 INFO - Reading from file tmpfile_stdout 02:39:57 INFO - Detecting whether we're running mozinstall >=1.0... 02:39:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:39:57 INFO - Reading from file tmpfile_stdout 02:39:57 INFO - Output received: 02:39:57 INFO - Usage: mozinstall [options] installer 02:39:57 INFO - Options: 02:39:57 INFO - -h, --help show this help message and exit 02:39:57 INFO - -d DEST, --destination=DEST 02:39:57 INFO - Directory to install application into. [default: 02:39:57 INFO - "/builds/slave/test"] 02:39:57 INFO - --app=APP Application being installed. [default: firefox] 02:39:57 INFO - mkdir: /builds/slave/test/build/application 02:39:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:40:31 INFO - Reading from file tmpfile_stdout 02:40:31 INFO - Output received: 02:40:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:40:31 INFO - Running post-action listener: _resource_record_post_action 02:40:31 INFO - ##### 02:40:31 INFO - ##### Running run-tests step. 02:40:31 INFO - ##### 02:40:31 INFO - Running pre-action listener: _resource_record_pre_action 02:40:31 INFO - Running main action method: run_tests 02:40:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:40:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:40:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:40:31 INFO - 2016-04-15 02:40:31.155 screenresolution[1642:7382] starting screenresolution argv=screenresolution get 02:40:31 INFO - 2016-04-15 02:40:31.158 screenresolution[1642:7382] Display 0: 1600x1200x32@60 02:40:31 INFO - 2016-04-15 02:40:31.163 screenresolution[1643:7384] starting screenresolution argv=screenresolution list 02:40:31 INFO - Available Modes on Display 0 02:40:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:40:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:40:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:40:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:40:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:40:31 INFO - Intel Iris: 02:40:31 INFO - Chipset Model: Intel Iris 02:40:31 INFO - Type: GPU 02:40:31 INFO - Bus: Built-In 02:40:31 INFO - VRAM (Dynamic, Max): 1536 MB 02:40:31 INFO - Vendor: Intel (0x8086) 02:40:31 INFO - Device ID: 0x0a2e 02:40:31 INFO - Revision ID: 0x0009 02:40:31 INFO - Displays: 02:40:31 INFO - Display: 02:40:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:40:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:40:31 INFO - Display Serial Number: 1600x1200 60 02:40:31 INFO - Main Display: Yes 02:40:31 INFO - Mirror: Off 02:40:31 INFO - Online: Yes 02:40:31 INFO - Rotation: Supported 02:40:31 INFO - Return code: 0 02:40:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:40:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:40:31 INFO - Python 2.7.3 02:40:31 INFO - Return code: 0 02:40:31 INFO - grabbing minidump binary from tooltool 02:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:40:31 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 0x10a131e00>, '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 0x109f5adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a12cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:40:31 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 02:40:31 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 02:40:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:40:31 INFO - Return code: 0 02:40:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:40:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:40:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:40:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:40:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:40:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:40:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:40:32 INFO - Return code: 0 02:40:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 02:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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 02:40:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f49zopnQlP/Render', 02:40:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:40:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:40:32 INFO - 'HOME': '/Users/cltbld', 02:40:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:40:32 INFO - 'LOGNAME': 'cltbld', 02:40:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:40:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:40:32 INFO - 'MOZ_NO_REMOTE': '1', 02:40:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:40:32 INFO - 'NO_EM_RESTART': '1', 02:40:32 INFO - 'PAGER': '/bin/cat', 02:40:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:40:32 INFO - 'PWD': '/builds/slave/test', 02:40:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:40:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:40:32 INFO - 'SHELL': '/bin/bash', 02:40:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fPbTyEq5bi/Listeners', 02:40:32 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 02:40:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:40:32 INFO - 'USER': 'cltbld', 02:40:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:40:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:40:32 INFO - 'XPC_FLAGS': '0x0', 02:40:32 INFO - 'XPC_SERVICE_NAME': '0', 02:40:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:40:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 02:40:32 INFO - mozversion application_buildid: 20160415014444 02:40:32 INFO - mozversion application_changeset: d0c2f09a6285ad7a9f25fcb704de38c9c5168369 02:40:32 INFO - mozversion application_display_name: Nightly 02:40:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:40:32 INFO - mozversion application_name: Firefox 02:40:32 INFO - mozversion application_remotingname: firefox 02:40:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:40:32 INFO - mozversion application_vendor: Mozilla 02:40:32 INFO - mozversion application_version: 48.0a1 02:40:32 INFO - mozversion platform_buildid: 20160415014444 02:40:32 INFO - mozversion platform_changeset: d0c2f09a6285ad7a9f25fcb704de38c9c5168369 02:40:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:40:32 INFO - mozversion platform_version: 48.0a1 02:40:32 INFO - using testdate: 1460713232 02:40:32 INFO - actual date: 1460713232 02:40:32 INFO - starting webserver on 'localhost:49271' 02:40:32 INFO - SUITE-START | Running 2 tests 02:40:32 INFO - TEST-START | dromaeo_css 02:40:32 INFO - operating with platform_type : mac_ 02:40:32 INFO - Initialising browser for dromaeo_css test... 02:40:33 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpySNxv_/profile http://localhost:49271/getInfo.html) 02:40:35 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 02:40:35 INFO - PROCESS | 1661 | colorDepth:24 02:40:35 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 02:40:35 INFO - PROCESS | 1661 | __metrics 02:40:35 INFO - PROCESS | 1661 | 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" 02:40:35 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:40:36 INFO - TEST-INFO | 1661: exit 0 02:40:36 INFO - Browser initialized. 02:40:36 INFO - Running cycle 1/1 for dromaeo_css test... 02:40:36 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpySNxv_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:40:47 INFO - PROCESS | 1663 | RSS: Main: 237723648 02:40:47 INFO - PROCESS | 1663 | 02:41:39 INFO - PROCESS | 1663 | 1460713299434 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] 02:43:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 02:43:51 INFO - PROCESS | 1663 | RSS: Main: 326975488 02:43:51 INFO - PROCESS | 1663 | 02:46:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 02:46:54 INFO - PROCESS | 1663 | RSS: Main: 380506112 02:46:54 INFO - PROCESS | 1663 | 02:49:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 02:49:57 INFO - PROCESS | 1663 | RSS: Main: 374333440 02:49:57 INFO - PROCESS | 1663 | 02:52:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 02:52:59 INFO - PROCESS | 1663 | RSS: Main: 353632256 02:52:59 INFO - PROCESS | 1663 | 02:56:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 02:56:03 INFO - PROCESS | 1663 | RSS: Main: 339140608 02:56:03 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 02:59:06 INFO - PROCESS | 1663 | RSS: Main: 381251584 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | __start_tp_report 02:59:06 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 02:59:06 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 02:59:06 INFO - PROCESS | 1663 | |i|pagename|runs| 02:59:06 INFO - PROCESS | 1663 | |0;dojo.html;3375.3418083473725;3447.9311413461846;3478.5825700915884;3480.0603836964005;3521.1901262709575;5295.258663787073;5517.062099443095;5604.271444712187;5608.10270356743;5634.7182640867895;5597.272354593897;5608;5673.035583950728;5759.280089988752;5766.0197766379715;4168.228877657628;5069.163588007984;5082.288479612857;5104.897902041976;5253.133233016548;5175.715335656538;5200.5059519345605;5205.895882082356;5227.111391063529;5277.8416647500635;5047.141985862412;5133.383993920754;5142.768575414116;5144.61415393847;5170.844874653766;5058.54473097423;5077.187649976023;5111.437741848431;5176.55999240065;5176.637635365489;5059.569936555397;5070.264811602297;5096.159133742965;5126.6667666601925;5161.045206636784;5439.836804895859;5533.944660553383;5550.8057217997175;5576.693281869496;5589.74846131921;5996.97001514994;6115.847103822395;6168.074788781674;6170.7531698732;6180.660063696495;6158.353372895826;6162.630242185483;6226.844328891769;6227.87544249117;6243.56295059346;5524;5705;5709.486146246858;5709.68596727184;5729.598928075036;6212.223472065992;6317.46301564363;6319.873602527924;6335.239771227482;6340.239171299381;6003.759849605967;6006.789762358297;6014.338422773491;6014.8195554133445;6033.607815491979;6036.426539478743;6116.48009919153;6137.754489820357;6178.41305076017;6201.379862013762;6004.099385092271;6024.969875150597;6026.005709058044;6035.668038257938;6043.063325184604;5984.611000284967;6041.214642096499;6063.484603808725;6063.848403789941;6076.605020673642;4777.9044419112115;4843.709377437365;4850.029994001214;4922.384701912261;4938.160512712848;4250.468691413574;4358.542353052947;4363.912721745547;4384.276594361958;4413.271810151353;5575.135853942565;5594.021046316879;5600.5799565032785;5645.181448689917;5659.603827732019;6059.303180134253;6086.969565152146;6143.941779503916;6145.692715364304;6146.739918316824;5557.055300598908;5568.7215639217875;5574.498295153456;5586.804461843897;5621.690807005572;5263.736813159327;5263.921041184385;5274.868128296746;5281.313429254173;5327.840164795062;6186.783462578878;6288;6289.496840252768;6307.653079080695;6313.084602732578;6106.6336019838955;6108.4807791338235;6109.908351374822;6123.9693801532485;6132.988056970728;6203.937960620336;6264.780732674334;6291.622502649856;6296.464800492009;6304.085907543289;6094.018026236212;6115.541334399849;6121.265448146251;6125.540584456273;6128.4790792783115;6143.69941041264;6167.105769663463;6168.074788781719;6208.161898143693;6210.7515699371515;5804.883902322014;5933.851653708692;5936.495397891227;6013.925627422496;6038.758449661965;5938.762449501972;5947.524198064231;5983.132445795422;5984.910226346521;6008.939910601013;5185.844424667266;5366.651167674088;5841.94158058431;5844.824655260349;5855.063189889597;5844.357120716632;5930.199423077829;5944.613600115978;6023.789167379121;6080.361562035922;5771.884562308646;5787.074068149076;5795.681237531976;5800.738966746422;5801.970990145022 02:59:06 INFO - PROCESS | 1663 | |1;ext.html;485.2890515394948;520.7422325948656;529.2273281009727;529.6319058254513;543.3344153412071;28387.29031774204;29965.550516742238;30071.248218794542;39053.41419878697;39138.21723565534;42571.36142957858;43707;43948;43961.56038439622;44018;9487.667931622394;9677.806443871119;9685.564149613248;9697.418154910692;9704.22366210705;9386.20217281532;9526.571304291288;9528.904710952906;9539.47532885691;9541.809163816719;9505.287103467233;9551.522423878812;9573.617055317778;9580.568874400635;9583.233341332676;9482.81034379312;9530.237580993504;9545.236381089497;9574.425534467884;9583.616655333813;9408.952955235249;9492.382995105365;9496.097870702271;9496.14534691881;9498.620055197784;38328.80835595804;38604.420933686015;38772;38826.22347553061;38876;3195.328980914011;3235.854386552611;3378.2061215614312;3414.5219669246326;3808.1812410331913;26941.865290673617;26958;27369.99476739848;27849;28029.57955630667;2719.619253304539;2733.685626152978;2767.266674331304;2794.203651959202;2808.817426867247;403126;405428;405565;406023;406109.69451527303;35027.4745878812;35778.92663220107;61678.69160654168;62173.06740398898;62230.68884655638;34975.950721478395;35098.29803403969;35207.4718879217;35250.823745881105;35320;14723.705525889422;14878.181700006387;14880.479183228535;14882;14898.627534311512;12590.496380144692;12659.860612544915;12677.873221267853;12691;12710.618681439571;8043.19568043191;8060.596970151469;8066.475679080841;8146.022477302761;8146.2261085196815;3296.093574267096;3331.4336562784383;3331.6335203127637;3337.2658015236607;3368.494725791151;959.4483172176027;972.7957129002989;1040.0379648824808;1050.3225419604464;1081.4754843900703;27091.864540677172;27458;27928;29100;29148;1035.1822060572215;1042.280826229899;1078.1590359519587;1117.94969226385;1119.2445099557929;14192.716145677028;14340.426382944564;14434.845212382985;14490.565283041526;14527.636809079646;627918;638295;643360;645883;646511;56651.15023274654;57535;57562.136567951515;57628;57812.42187578155;1343.4894739998738;1391.6520869782555;1465.1062851660265;1477.6453651123868;1498.9550313490613;28249.293767655974;28481.28796780097;29187.85406072956;29619.96330128435;29681.40637187201;40215;48989.26516102332;49560.50439495559;49607.25589116094;49775.75112124561;424859;427026;427171;428316;428729;23060.308190754306;25256.873715631304;25650;25815.354616134748;26173.345666358495;41651.37522937098;41852.53516126921;42390;42534.361984570874;42969.3554596675;34733.826330868185;34852.477212842336;34893.651063489044;35744.28511429809;35912.743053992985;33791.83104084464;34226.65773342235;34378.82810585931;34498.82750586231;34963.951081467596;30118.84940575283;31716.524252136696;31797.20506987344;32226.838865806458;36020.63979360173;27228.863855680596;30617.846910765304;30648.94292441722;30679.693203068575;31031;22941.770582293964;22974.540509189388;23193.88403057974;23737.406564835328;24044.75955240425 02:59:06 INFO - PROCESS | 1663 | |2;jquery.html;386.7582760774517;389.79730540119135;392.09035038710186;396.90829329433865;398.48396326756847;14578.052426592265;17458.47624571265;19068;19407.417777466664;19781.50546236343;13250.33748312583;19110.235590576394;19691.901540492276;20066.297679581217;20072.899635501803;5567.4432556744205;5659.236003139565;5861.970690146564;5866.882662346772;5875.676837773921;3419.880304189354;5713.371529131792;5727.398623144572;5791.942080579182;6026;6326.620402775849;6347.174867267248;6358.364163583651;6361.459275961549;6362.427381535661;5604.842135777714;6148.8462788430425;6344.238691357021;6434.485241180694;6447.194100737408;5585.301837270341;5603.075492543725;5610.18652295419;5620.4660557247;5622.297212848394;23094.845257737044;24234.757652423425;24461.755382446125;24723.134690285755;25578.616320755205;1909.2744756992392;2001.559656875485;2112.588045331161;2124.564464284818;2273.8749368784875;9301.348905576613;10013.799724005552;10040.397576145455;10185.847212291821;13309.334533273297;1637.3450619752075;1644.5313085770617;1705.8550023247915;1726.620143568413;1729.3082766893217;83400;103845;111302.44348778205;111880;112969.43515282453;9472.95263523678;11465.828012579652;11551.595694150665;11579.76840463186;11677;13553.186808791505;14177.291135443187;14795.482158124414;14807.111573305636;14853.628659283604;7042.330978557029;8109.432339736162;8402.957985210034;8505.404621676547;8609.784755381166;8338;8354.707585234488;8365.539895305696;8454.027786804596;8462.280706140047;4473.642108631302;4541.227991241497;4551.180787458289;4602.286645569942;4633.305004249322;1844.9354272600394;1935.3032908153054;1943.4946913802514;1947.1140631013138;1954.2476146683346;749.7226026370278;792.3146478296233;855.6491838346249;909.554318383987;948.9051094890456;16977.745333820003;19048.61902761937;20068.899655501627;20169.294074707315;20173.59652806965;930.4835816122118;947.6067432015774;948.4593781544592;956.5073986896754;995.64654547636;10338.741531461623;10511.737206569744;10516.159672057513;10552.577896884039;10830.404327761891;591147;591398.0430097822;599417;609938;610870;118565.40717296358;119789.40105299592;119928.40035799766;121152.78847211415;122941.77058229482;1008.7074748323051;1019.1642852860583;1039.4750650921237;1039.5685790397051;1039.9064084232455;17421.47735567935;17727.025013623857;17752.2011509485;17784.110794459968;17969.550761231072;48472.757636211594;48639.756801215764;49299.26051109165;49934.50065499444;50299;71632.28367716464;72568.27431725615;72599;73276.63361683158;73779;5974.820755377345;7868.685252589832;8054.355651547745;8057.153998830273;8090.757277281691;9132.954335228282;11816.40917954116;12124.696882577653;12161.33112678811;12374.75250494967;10452.268341216042;10666.46667666629;10730.409827459565;10885.510152043018;10932.890671093188;7655.540667559964;10330.638427654996;10575.735606609896;10683.30558513694;10706.73233169177;10674.519646615765;10794.784104317712;10878.40168790724;10921.017108460277;11110.111191104854;7931.144787876245;7961.721339753312;8034.47775894565;8045.316148127475;8391.412601117863;9448.826705404499;9565.521723913636;10475.004874536908;10833.404162771123;10971.451427428437 02:59:06 INFO - PROCESS | 1663 | |3;mootools.html;1427.1508452470775;1612.725836607777;1628.617274940389;1864.2729335559127;1976.1700085963903;7788.922110778891;8479.109693482189;8688.826223475535;8695.826083478327;8710.433821801595;8335.54154521501;8559.315254779618;8562.186592273723;8578.056413794478;8583.227509524142;2144.1852096203497;2248.7076680031573;2277.032261288954;2286.622707253301;2355.281639100074;2446.5351583199135;2470.962935555968;2474.604063349864;2480.255923223035;2480.516299321633;2321.7926678127365;2396.400899775056;2422.709274887016;2433.0267892842826;2446.412860913386;1915.9137838797287;2121.5756848630335;2173.5870184664864;2337.9532409351887;2419.9637005444843;2403.951920961571;2429.975700242993;2431.7203521595065;2434.624437192998;2435.439848834778;8423.915760842374;8505;8507;8523.829523409559;8525.74422767318;4991.525805048551;5168.483151684839;5181.53366197044;5193.195054766517;5211.7394130293715;3258.413485572596;3339.7996120232865;3446.6553344665585;3457.9827100864336;3458.066322093046;987.2891518106951;989.7921436498344;1008.591520434218;1018.332991890313;1599.8240193578697;11231.157663175294;11320.547178112865;11357.261777984404;11361.43192840363;11385.032272256869;4218.599233072853;4284.186004659105;4535.433070866142;4619.0530941156985;4621.075784843045;8747.037825839152;8821.338399620054;8837.955810220908;8850.380473366931;8851.07063758309;5107.565856902131;5139.537441630197;5157.123289040872;5160.148575485078;5181.4300426953005;4341.587549182823;4526.7510286933875;4685;4796.088743138793;4832.130216561053;3729.757565758217;3735.551733791908;3828.9234215315537;3842.058695619591;3844.154286057063;1073.6564299424265;1195.49789088583;1200.5617949448388;1213.881776300344;1215.9270443773403;810.3841080778651;842.3850589070013;908.4322298563397;932.296116432103;945.8155659646303;5280.7359632019015;5463.590230732711;5668.8095499945475;5681.289838770153;5718.656880587178;439.7625282347561;462.29500012481236;930.4556834251903;940.753501610369;1062.7343164208949;3734.8319325630414;3895.14306852492;3968.730006397983;3972.483577134954;3974.3839704845795;10106.696799096038;10513.106385957126;10560.841587376195;10568.894311056945;10589.258751887448;10162.339447935861;10353.327033742782;10494.160467162608;10606.257561970588;10641.361518308926;7834.960825195838;8018.599070046591;8121.878171827428;8147.388945828968;8159.265666089962;5595.972020139873;5704.572157088318;5752.654840709571;5772.509336706259;5994.520438365008;9420.293477989042;9440;9733.610655573697;9827.4103553787;9837.606495740376;9396.67111651089;9844.950775246078;9853.95073024659;9940.304178707422;9957.302988791005;6738.46092312604;6828.112345395067;6918.295559019914;7235.782926512213;7465.776026719207;7686.884696729442;7746.419018573517;7772.7668169954995;8236.50580965144;8268.131846156308;7273.636318184175;7307.3423391894985;7310.122785265802;7316.304951029643;7474.7757567273065;7241.674124664298;7253.891191632024;7291.380232680282;7331.4134869209265;7409.66656500448;6800.761973330909;6816.113905192492;6874.415674667509;6907.792766217021;6931.44548436134;4781.737004464788;4825.710457372569;4833.879153021062;4860.781264843161;4923.803047878045;4858.4898585648;4874.147024270667;4947.727874966911;4953;4988.152014157529 02:59:06 INFO - PROCESS | 1663 | |4;prototype.html;274.85844789933174;295.4947040560641;305.1592861666108;326.36521964778484;327.8393587142301;4874.903791369709;5777.480026797587;5789.594728369016;5848.795292164775;5856.619319744224;5075.670081444703;5276.865473923106;5430.945690543093;5687.345955215152;5749.942500574993;4305.461817272841;4327.956720432787;4336.653067754588;4355.107203023388;4371.36615190798;4335.739855608674;4346.282863327548;4351.6953813233085;4365.2142614329405;4375.7374557526655;3573.321068996895;4133.751974881517;4282.614564689177;4290.957090429087;4313.396124542566;3563.821808909544;4268.63090984505;4302.161078589676;4306.61240488356;4313.123791150828;3679.908002299937;3708.7589306694977;3718.7025037997164;3735.4396840473873;3773.9245215095543;4861.781219845116;5147.7683504242395;5249.42256351801;5560.916586251209;5577.498025177753;4515.277555591089;4802.951970480321;4882.5605695487575;4921.18800397934;4940.925886111675;5388.865278368072;6061.302950160743;6086.695665216722;6107.083937409379;6117.174181485487;3184.251700850298;3810.913889541495;4064.654504367132;4093.4269202311702;4101.425800387948;5048.29323894655;5992.880142397127;6187.5668703190795;6299.181106456177;6375.266844312917;3318.5685860837934;4128.91742165161;4138.91722165561;4154.31453810124;4180.47744031996;4050.736702114353;4174.457320548309;4193.958060419357;4209.2423363794815;4225.408442818008;3986.282469155522;4138.896527586834;4878.414590249122;4912.463026620827;4962.751862406865;6133;6148.938510614837;6160.8414320333595;6167.876642467126;6234.251889773256;1218.366449446283;1294.2428679222571;1371.8559551246976;1390.4079828596402;1402.7615305398108;940.3934462271797;955.9150364336529;961.7211008807508;961.7980224152866;965.2181732796457;5577.107662773937;5626.85932851682;5688.687122208318;5688.914666280009;5689.2603961485565;5168.328117344721;5955.910661340084;6327.114204011442;6328.525360597881;6330.012092381862;5589.888202235932;5597.132444471114;5597.552195824405;5606.859828504238;5629.521490673339;2946.7937244392688;3151.73210277129;3296.7527435441957;3325.8004519728893;3337.3825842219267;5390.51485366319;6227.377262273827;6305.8108256752375;6529.11856899322;6619.834504137435;5906.7637294508595;6274.905876411857;6487.351264873475;6505.186851643544;6513.609183448913;5809.564282678816;5825.94174058254;6222.222222222132;6629.320726996467;6643.701033453506;5532.391436942014;6273.686315684289;6287.96856015717;6308.306086330408;6337.4613157882095;6206.875862482815;6233.532485063729;6261.154744109669;6269.5611307208055;6287.245530536182;5736.996025695403;6257.937420625735;6659.900101498384;6760.628165450948;6773.62745049027;3753.0617345663586;4121.907694461031;4125.958740412558;4204.747715137101;4224.809883555309;5613.971930140323;5638.646724786104;5808.5062769665055;6234.0025595904435;6417.614943103429;6101.969490152698;6146.139540464428;6173.259208894961;6182.567220294716;6205.2553693557065;5098.923516147335;5977.193078934288;6003.219581454383;6139.2325959255095;6181.505479561714;5310.495502927216;5790.449907258803;5853.483002309048;6039.335673075871;6084.117802918464;5561.3882472927135;6164.167837342081;6175;6194.876102477835;6212.844678883064;5078.949210507995;6032.819015429544;6055.364186760503;6087;6122.959096953439 02:59:06 INFO - PROCESS | 1663 | |5;yui.html;667.6194569095614;725.9818504537383;737.9743499211799;743.3978477433279;801.6953557648093;15327.770083448757;15585.29866156023;15601.375944962216;15616.06303621782;15630.531084067468;14571.344289506971;14603;14628.4880029199;14635.341409636565;14650.780238296435;723.7249845058897;723.9058922340089;743.8289193485501;889.4174315823146;900.1178844732167;910.9271258299353;917.156216281023;917.9770505737343;921.78338090549;921.8110287391069;963.5519483440199;971.0823272007955;973.6397532912832;979.9951000244989;981.092489447262;766.1074847802304;962.7785609309898;964.2382517810956;966.3815158298695;966.4877614864133;713.0302788208033;736.9078865141857;737.1485933746532;739.1536690489413;739.8002539314409;14921.925390373088;14946.850531494654;14954.327055282538;14992.70014599702;14994.700105997928;12553.184043037174;12630.621081367573;12797.168184068007;12970.416331265022;13055.86944130556;9744.658936937172;9913.900860991442;10095.949520252425;10381.065704086594;10974.725631859188;3500.9474857877153;4010.7593544387432;4051.372037334218;4055.2092341993325;4377.299632058887;78365.60817195878;79689.40621187602;80658.59670701668;80842;81159;9622.13400793932;9800;9885.851712224323;9906.900930990741;10145.239107067;22035.449113772283;22274.554508909732;22321.330360089225;22350.441238969157;22712.091516339162;6165.630062196283;6180.072989051589;6200.627962322275;6228.159198508143;6353.96823015882;4015.357542793139;4085.571015043437;4160;4186.937195942063;4295.978520107379;3479.8260086995547;3592.0660628236847;3614;3624.257027309395;3641.9817900910325;643.1767337807614;654.4894981914115;658.2627457247818;662.8972509261072;664.5613894829486;507.284728532767;556.7550277877801;562.9690367029852;570.3298624116646;580.0574067140896;7356.411487081021;7572.621368931532;7808.336291415179;7922.7227047053075;7953.602319883983;538.4023733655639;555.9583031272671;557.5762420560403;570.9457601527848;574.7873286883796;3465.8960231193105;3686.4838922550866;3733.290674771785;3761.1349389640536;3839.3856982882603;81825.59087204645;82851.17148828435;83028.58485707654;85737;87031.56484217539;66753;67255.66372168205;72180.63909680418;75252.24747752452;75314;44653.43712970096;45685.77157114193;60494.09258861121;63400.682996585645;63414.68292658599;7721.343685786771;7795.766127016199;7823.569703666353;7847.646855891613;8108.391870609846;42720.78639606782;43013.56986430095;43052.78473607612;43432;43432.565674342855;51662.708432289495;58638.12042819276;61514;62279.37720622917;62714;10045.397276163454;10079.748006299902;10160.644377446753;10250.743731406774;10523.894761052597;11284.32294062359;11325.886741132812;11422.371769552423;11753.764924701629;11831.881681183422;10445.321054131457;10814.026737593655;11002.119830413387;11048.613298534512;11535.480903359201;10432.947835260775;10453.9477302613;10889.128869690247;10917;11134.554617815196;8522.360822938428;10261.897381026094;10440.63457778974;10612.310199836986;10674.46627668629;9122.589483473392;9143;9241.861372079558;9490.430574165572;9582.808343833223;9070;9279.860802087838;9307.813843723223;9400.811983760423;9448.622055117718 02:59:06 INFO - PROCESS | 1663 | __end_tp_report 02:59:06 INFO - PROCESS | 1663 | __start_cc_report 02:59:06 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1173 02:59:06 INFO - PROCESS | 1663 | __end_cc_report 02:59:06 INFO - PROCESS | 1663 | __startTimestamp1460714346678__endTimestamp 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | ------- Summary: start ------- 02:59:06 INFO - PROCESS | 1663 | Number of tests: 6 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5646.02 Median:5804.88 stddev:624.03 (10.8%) stddev-sans-first:599.98 02:59:06 INFO - PROCESS | 1663 | Values: 3375.3 3447.9 3478.6 3480.1 3521.2 5295.3 5517.1 5604.3 5608.1 5634.7 5597.3 5608.0 5673.0 5759.3 5766.0 4168.2 5069.2 5082.3 5104.9 5253.1 5175.7 5200.5 5205.9 5227.1 5277.8 5047.1 5133.4 5142.8 5144.6 5170.8 5058.5 5077.2 5111.4 5176.6 5176.6 5059.6 5070.3 5096.2 5126.7 5161.0 5439.8 5533.9 5550.8 5576.7 5589.7 5997.0 6115.8 6168.1 6170.8 6180.7 6158.4 6162.6 6226.8 6227.9 6243.6 5524.0 5705.0 5709.5 5709.7 5729.6 6212.2 6317.5 6319.9 6335.2 6340.2 6003.8 6006.8 6014.3 6014.8 6033.6 6036.4 6116.5 6137.8 6178.4 6201.4 6004.1 6025.0 6026.0 6035.7 6043.1 5984.6 6041.2 6063.5 6063.8 6076.6 4777.9 4843.7 4850.0 4922.4 4938.2 4250.5 4358.5 4363.9 4384.3 4413.3 5575.1 5594.0 5600.6 5645.2 5659.6 6059.3 6087.0 6143.9 6145.7 6146.7 5557.1 5568.7 5574.5 5586.8 5621.7 5263.7 5263.9 5274.9 5281.3 5327.8 6186.8 6288.0 6289.5 6307.7 6313.1 6106.6 6108.5 6109.9 6124.0 6133.0 6203.9 6264.8 6291.6 6296.5 6304.1 6094.0 6115.5 6121.3 6125.5 6128.5 6143.7 6167.1 6168.1 6208.2 6210.8 5804.9 5933.9 5936.5 6013.9 6038.8 5938.8 5947.5 5983.1 5984.9 6008.9 5185.8 5366.7 5841.9 5844.8 5855.1 5844.4 5930.2 5944.6 6023.8 6080.4 5771.9 5787.1 5795.7 5800.7 5802.0 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:61080.96 Median:35229.15 stddev:134500.25 (381.8%) stddev-sans-first:134800.61 02:59:06 INFO - PROCESS | 1663 | Values: 485.3 520.7 529.2 529.6 543.3 28387.3 29965.6 30071.2 39053.4 39138.2 42571.4 43707.0 43948.0 43961.6 44018.0 9487.7 9677.8 9685.6 9697.4 9704.2 9386.2 9526.6 9528.9 9539.5 9541.8 9505.3 9551.5 9573.6 9580.6 9583.2 9482.8 9530.2 9545.2 9574.4 9583.6 9409.0 9492.4 9496.1 9496.1 9498.6 38328.8 38604.4 38772.0 38826.2 38876.0 3195.3 3235.9 3378.2 3414.5 3808.2 26941.9 26958.0 27370.0 27849.0 28029.6 2719.6 2733.7 2767.3 2794.2 2808.8 403126.0 405428.0 405565.0 406023.0 406109.7 35027.5 35778.9 61678.7 62173.1 62230.7 34976.0 35098.3 35207.5 35250.8 35320.0 14723.7 14878.2 14880.5 14882.0 14898.6 12590.5 12659.9 12677.9 12691.0 12710.6 8043.2 8060.6 8066.5 8146.0 8146.2 3296.1 3331.4 3331.6 3337.3 3368.5 959.4 972.8 1040.0 1050.3 1081.5 27091.9 27458.0 27928.0 29100.0 29148.0 1035.2 1042.3 1078.2 1117.9 1119.2 14192.7 14340.4 14434.8 14490.6 14527.6 627918.0 638295.0 643360.0 645883.0 646511.0 56651.2 57535.0 57562.1 57628.0 57812.4 1343.5 1391.7 1465.1 1477.6 1499.0 28249.3 28481.3 29187.9 29620.0 29681.4 40215.0 48989.3 49560.5 49607.3 49775.8 424859.0 427026.0 427171.0 428316.0 428729.0 23060.3 25256.9 25650.0 25815.4 26173.3 41651.4 41852.5 42390.0 42534.4 42969.4 34733.8 34852.5 34893.7 35744.3 35912.7 33791.8 34226.7 34378.8 34498.8 34964.0 30118.8 31716.5 31797.2 32226.8 36020.6 27228.9 30617.8 30648.9 30679.7 31031.0 22941.8 22974.5 23193.9 23737.4 24044.8 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:33910.73 Median:24592.45 stddev:99752.76 (405.6%) stddev-sans-first:100000.83 02:59:06 INFO - PROCESS | 1663 | Values: 386.8 389.8 392.1 396.9 398.5 14578.1 17458.5 19068.0 19407.4 19781.5 13250.3 19110.2 19691.9 20066.3 20072.9 5567.4 5659.2 5862.0 5866.9 5875.7 3419.9 5713.4 5727.4 5791.9 6026.0 6326.6 6347.2 6358.4 6361.5 6362.4 5604.8 6148.8 6344.2 6434.5 6447.2 5585.3 5603.1 5610.2 5620.5 5622.3 23094.8 24234.8 24461.8 24723.1 25578.6 1909.3 2001.6 2112.6 2124.6 2273.9 9301.3 10013.8 10040.4 10185.8 13309.3 1637.3 1644.5 1705.9 1726.6 1729.3 83400.0 103845.0 111302.4 111880.0 112969.4 9473.0 11465.8 11551.6 11579.8 11677.0 13553.2 14177.3 14795.5 14807.1 14853.6 7042.3 8109.4 8403.0 8505.4 8609.8 8338.0 8354.7 8365.5 8454.0 8462.3 4473.6 4541.2 4551.2 4602.3 4633.3 1844.9 1935.3 1943.5 1947.1 1954.2 749.7 792.3 855.6 909.6 948.9 16977.7 19048.6 20068.9 20169.3 20173.6 930.5 947.6 948.5 956.5 995.6 10338.7 10511.7 10516.2 10552.6 10830.4 591147.0 591398.0 599417.0 609938.0 610870.0 118565.4 119789.4 119928.4 121152.8 122941.8 1008.7 1019.2 1039.5 1039.6 1039.9 17421.5 17727.0 17752.2 17784.1 17969.6 48472.8 48639.8 49299.3 49934.5 50299.0 71632.3 72568.3 72599.0 73276.6 73779.0 5974.8 7868.7 8054.4 8057.2 8090.8 9133.0 11816.4 12124.7 12161.3 12374.8 10452.3 10666.5 10730.4 10885.5 10932.9 7655.5 10330.6 10575.7 10683.3 10706.7 10674.5 10794.8 10878.4 10921.0 11110.1 7931.1 7961.7 8034.5 8045.3 8391.4 9448.8 9565.5 10475.0 10833.4 10971.5 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5543.31 Median:4989.84 stddev:3078.76 (61.7%) stddev-sans-first:3071.86 02:59:06 INFO - PROCESS | 1663 | Values: 1427.2 1612.7 1628.6 1864.3 1976.2 7788.9 8479.1 8688.8 8695.8 8710.4 8335.5 8559.3 8562.2 8578.1 8583.2 2144.2 2248.7 2277.0 2286.6 2355.3 2446.5 2471.0 2474.6 2480.3 2480.5 2321.8 2396.4 2422.7 2433.0 2446.4 1915.9 2121.6 2173.6 2338.0 2420.0 2404.0 2430.0 2431.7 2434.6 2435.4 8423.9 8505.0 8507.0 8523.8 8525.7 4991.5 5168.5 5181.5 5193.2 5211.7 3258.4 3339.8 3446.7 3458.0 3458.1 987.3 989.8 1008.6 1018.3 1599.8 11231.2 11320.5 11357.3 11361.4 11385.0 4218.6 4284.2 4535.4 4619.1 4621.1 8747.0 8821.3 8838.0 8850.4 8851.1 5107.6 5139.5 5157.1 5160.1 5181.4 4341.6 4526.8 4685.0 4796.1 4832.1 3729.8 3735.6 3828.9 3842.1 3844.2 1073.7 1195.5 1200.6 1213.9 1215.9 810.4 842.4 908.4 932.3 945.8 5280.7 5463.6 5668.8 5681.3 5718.7 439.8 462.3 930.5 940.8 1062.7 3734.8 3895.1 3968.7 3972.5 3974.4 10106.7 10513.1 10560.8 10568.9 10589.3 10162.3 10353.3 10494.2 10606.3 10641.4 7835.0 8018.6 8121.9 8147.4 8159.3 5596.0 5704.6 5752.7 5772.5 5994.5 9420.3 9440.0 9733.6 9827.4 9837.6 9396.7 9845.0 9854.0 9940.3 9957.3 6738.5 6828.1 6918.3 7235.8 7465.8 7686.9 7746.4 7772.8 8236.5 8268.1 7273.6 7307.3 7310.1 7316.3 7474.8 7241.7 7253.9 7291.4 7331.4 7409.7 6800.8 6816.1 6874.4 6907.8 6931.4 4781.7 4825.7 4833.9 4860.8 4923.8 4858.5 4874.1 4947.7 4953.0 4988.2 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4918.50 Median:5597.34 stddev:1554.69 (27.8%) stddev-sans-first:1519.48 02:59:06 INFO - PROCESS | 1663 | Values: 274.9 295.5 305.2 326.4 327.8 4874.9 5777.5 5789.6 5848.8 5856.6 5075.7 5276.9 5430.9 5687.3 5749.9 4305.5 4328.0 4336.7 4355.1 4371.4 4335.7 4346.3 4351.7 4365.2 4375.7 3573.3 4133.8 4282.6 4291.0 4313.4 3563.8 4268.6 4302.2 4306.6 4313.1 3679.9 3708.8 3718.7 3735.4 3773.9 4861.8 5147.8 5249.4 5560.9 5577.5 4515.3 4803.0 4882.6 4921.2 4940.9 5388.9 6061.3 6086.7 6107.1 6117.2 3184.3 3810.9 4064.7 4093.4 4101.4 5048.3 5992.9 6187.6 6299.2 6375.3 3318.6 4128.9 4138.9 4154.3 4180.5 4050.7 4174.5 4194.0 4209.2 4225.4 3986.3 4138.9 4878.4 4912.5 4962.8 6133.0 6148.9 6160.8 6167.9 6234.3 1218.4 1294.2 1371.9 1390.4 1402.8 940.4 955.9 961.7 961.8 965.2 5577.1 5626.9 5688.7 5688.9 5689.3 5168.3 5955.9 6327.1 6328.5 6330.0 5589.9 5597.1 5597.6 5606.9 5629.5 2946.8 3151.7 3296.8 3325.8 3337.4 5390.5 6227.4 6305.8 6529.1 6619.8 5906.8 6274.9 6487.4 6505.2 6513.6 5809.6 5825.9 6222.2 6629.3 6643.7 5532.4 6273.7 6288.0 6308.3 6337.5 6206.9 6233.5 6261.2 6269.6 6287.2 5737.0 6257.9 6659.9 6760.6 6773.6 3753.1 4121.9 4126.0 4204.7 4224.8 5614.0 5638.6 5808.5 6234.0 6417.6 6102.0 6146.1 6173.3 6182.6 6205.3 5098.9 5977.2 6003.2 6139.2 6181.5 5310.5 5790.4 5853.5 6039.3 6084.1 5561.4 6164.2 6175.0 6194.9 6212.8 5078.9 6032.8 6055.4 6087.0 6123.0 02:59:06 INFO - PROCESS | 1663 | 02:59:06 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:16951.78 Median:29609.09 stddev:23240.64 (78.5%) stddev-sans-first:23273.67 02:59:06 INFO - PROCESS | 1663 | Values: 667.6 726.0 738.0 743.4 801.7 15327.8 15585.3 15601.4 15616.1 15630.5 14571.3 14603.0 14628.5 14635.3 14650.8 723.7 723.9 743.8 889.4 900.1 910.9 917.2 918.0 921.8 921.8 963.6 971.1 973.6 980.0 981.1 766.1 962.8 964.2 966.4 966.5 713.0 736.9 737.1 739.2 739.8 14921.9 14946.9 14954.3 14992.7 14994.7 12553.2 12630.6 12797.2 12970.4 13055.9 9744.7 9913.9 10095.9 10381.1 10974.7 3500.9 4010.8 4051.4 4055.2 4377.3 78365.6 79689.4 80658.6 80842.0 81159.0 9622.1 9800.0 9885.9 9906.9 10145.2 22035.4 22274.6 22321.3 22350.4 22712.1 6165.6 6180.1 6200.6 6228.2 6354.0 4015.4 4085.6 4160.0 4186.9 4296.0 3479.8 3592.1 3614.0 3624.3 3642.0 643.2 654.5 658.3 662.9 664.6 507.3 556.8 563.0 570.3 580.1 7356.4 7572.6 7808.3 7922.7 7953.6 538.4 556.0 557.6 570.9 574.8 3465.9 3686.5 3733.3 3761.1 3839.4 81825.6 82851.2 83028.6 85737.0 87031.6 66753.0 67255.7 72180.6 75252.2 75314.0 44653.4 45685.8 60494.1 63400.7 63414.7 7721.3 7795.8 7823.6 7847.6 8108.4 42720.8 43013.6 43052.8 43432.0 43432.6 51662.7 58638.1 61514.0 62279.4 62714.0 10045.4 10079.7 10160.6 10250.7 10523.9 11284.3 11325.9 11422.4 11753.8 11831.9 10445.3 10814.0 11002.1 11048.6 11535.5 10432.9 10453.9 10889.1 10917.0 11134.6 8522.4 10261.9 10440.6 10612.3 10674.5 9122.6 9143.0 9241.9 9490.4 9582.8 9070.0 9279.9 9307.8 9400.8 9448.6 02:59:06 INFO - PROCESS | 1663 | -------- Summary: end -------- 02:59:06 INFO - PROCESS | 1663 | 02:59:07 INFO - TEST-INFO | 1663: exit 0 02:59:07 INFO - TEST-OK | dromaeo_css | took 1114238ms 02:59:07 INFO - TEST-START | kraken 02:59:07 INFO - operating with platform_type : mac_ 02:59:07 INFO - Initialising browser for kraken test... 02:59:07 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpDJUoVn/profile http://localhost:49271/getInfo.html) 02:59:09 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 02:59:09 INFO - PROCESS | 1672 | colorDepth:24 02:59:09 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 02:59:09 INFO - PROCESS | 1672 | __metrics 02:59:09 INFO - PROCESS | 1672 | 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" 02:59:09 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:59:10 INFO - TEST-INFO | 1672: exit 0 02:59:10 INFO - Browser initialized. 02:59:10 INFO - Running cycle 1/1 for kraken test... 02:59:10 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpDJUoVn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:59:22 INFO - PROCESS | 1674 | RSS: Main: 237682688 02:59:22 INFO - PROCESS | 1674 | 02:59:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 02:59:25 INFO - PROCESS | 1674 | RSS: Main: 286908416 02:59:25 INFO - PROCESS | 1674 | 02:59:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 02:59:28 INFO - PROCESS | 1674 | RSS: Main: 299184128 02:59:28 INFO - PROCESS | 1674 | 02:59:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 02:59:33 INFO - PROCESS | 1674 | RSS: Main: 385646592 02:59:33 INFO - PROCESS | 1674 | 02:59:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 02:59:35 INFO - PROCESS | 1674 | RSS: Main: 285519872 02:59:35 INFO - PROCESS | 1674 | 02:59:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:59:38 INFO - PROCESS | 1674 | RSS: Main: 291487744 02:59:38 INFO - PROCESS | 1674 | 02:59:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 02:59:42 INFO - PROCESS | 1674 | RSS: Main: 327835648 02:59:42 INFO - PROCESS | 1674 | 02:59:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 02:59:45 INFO - PROCESS | 1674 | RSS: Main: 330289152 02:59:45 INFO - PROCESS | 1674 | 02:59:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 02:59:48 INFO - PROCESS | 1674 | RSS: Main: 326447104 02:59:48 INFO - PROCESS | 1674 | 02:59:50 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:59:50 INFO - PROCESS | 1674 | RSS: Main: 320135168 02:59:50 INFO - PROCESS | 1674 | 02:59:52 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:59:53 INFO - PROCESS | 1674 | RSS: Main: 328245248 02:59:53 INFO - PROCESS | 1674 | 02:59:55 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:59:56 INFO - PROCESS | 1674 | RSS: Main: 329994240 02:59:56 INFO - PROCESS | 1674 | 02:59:59 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:59:59 INFO - PROCESS | 1674 | RSS: Main: 331288576 02:59:59 INFO - PROCESS | 1674 | 03:00:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:00:03 INFO - PROCESS | 1674 | RSS: Main: 326750208 03:00:03 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 03:00:05 INFO - PROCESS | 1674 | RSS: Main: 390971392 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | __start_tp_report 03:00:05 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 03:00:05 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 03:00:05 INFO - PROCESS | 1674 | |i|pagename|runs| 03:00:05 INFO - PROCESS | 1674 | |0;ai-astar;111;110;111;113;112;110;119;113;112;112 03:00:05 INFO - PROCESS | 1674 | |1;audio-beat-detection;158;122;146;169;118;145;143;117;172;117 03:00:05 INFO - PROCESS | 1674 | |2;audio-dft;186;187;185;195;256;184;183;185;184;213 03:00:05 INFO - PROCESS | 1674 | |3;audio-fft;77;74;88;90;57;58;59;81;109;83 03:00:05 INFO - PROCESS | 1674 | |4;audio-oscillator;92;96;137;78;77;79;127;79;77;129 03:00:05 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;88;85;88;91;89;84;87;87;134;130 03:00:05 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;93;94;94;93;93;94;94;93;94 03:00:05 INFO - PROCESS | 1674 | |7;imaging-desaturate;84;84;85;86;87;87;85;86;91;86 03:00:05 INFO - PROCESS | 1674 | |8;json-parse-financial;59;60;60;60;60;58;59;77;58;57 03:00:05 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;77;73;71;72;66;67;68;67;68 03:00:05 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;85;84;85;85;84;83;83;84;84;86 03:00:05 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;149;157;138;150;133;142;154;128;251;184 03:00:05 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;169;167;168;169;171;170;178;172;167;165 03:00:05 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;68;66;68;67;68;68;68;69;66;68 03:00:05 INFO - PROCESS | 1674 | __end_tp_report 03:00:05 INFO - PROCESS | 1674 | __start_cc_report 03:00:05 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1904 03:00:05 INFO - PROCESS | 1674 | __end_cc_report 03:00:05 INFO - PROCESS | 1674 | __startTimestamp1460714405291__endTimestamp 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | ------- Summary: start ------- 03:00:05 INFO - PROCESS | 1674 | Number of tests: 14 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.00 stddev:2.58 (2.3%) stddev-sans-first:2.70 03:00:05 INFO - PROCESS | 1674 | Values: 111.0 110.0 111.0 113.0 112.0 110.0 119.0 113.0 112.0 112.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:145.50 stddev:21.40 (14.7%) stddev-sans-first:21.76 03:00:05 INFO - PROCESS | 1674 | Values: 158.0 122.0 146.0 169.0 118.0 145.0 143.0 117.0 172.0 117.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:195.80 Median:186.50 stddev:23.02 (12.3%) stddev-sans-first:24.14 03:00:05 INFO - PROCESS | 1674 | Values: 186.0 187.0 185.0 195.0 256.0 184.0 183.0 185.0 184.0 213.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:77.60 Median:79.00 stddev:16.52 (20.9%) stddev-sans-first:17.52 03:00:05 INFO - PROCESS | 1674 | Values: 77.0 74.0 88.0 90.0 57.0 58.0 59.0 81.0 109.0 83.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:78.50 stddev:24.40 (31.1%) stddev-sans-first:25.81 03:00:05 INFO - PROCESS | 1674 | Values: 92.0 96.0 137.0 78.0 77.0 79.0 127.0 79.0 77.0 129.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:96.30 Median:87.50 stddev:18.94 (21.6%) stddev-sans-first:19.85 03:00:05 INFO - PROCESS | 1674 | Values: 88.0 85.0 88.0 91.0 89.0 84.0 87.0 87.0 134.0 130.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 03:00:05 INFO - PROCESS | 1674 | Values: 93.0 93.0 94.0 94.0 93.0 93.0 94.0 94.0 93.0 94.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.00 stddev:2.02 (2.4%) stddev-sans-first:2.00 03:00:05 INFO - PROCESS | 1674 | Values: 84.0 84.0 85.0 86.0 87.0 87.0 85.0 86.0 91.0 86.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:60.00 stddev:5.79 (9.6%) stddev-sans-first:6.10 03:00:05 INFO - PROCESS | 1674 | Values: 59.0 60.0 60.0 60.0 60.0 58.0 59.0 77.0 58.0 57.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.50 stddev:3.63 (5.2%) stddev-sans-first:3.62 03:00:05 INFO - PROCESS | 1674 | Values: 66.0 77.0 73.0 71.0 72.0 66.0 67.0 68.0 67.0 68.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:0.95 (1.1%) stddev-sans-first:0.97 03:00:05 INFO - PROCESS | 1674 | Values: 85.0 84.0 85.0 85.0 84.0 83.0 83.0 84.0 84.0 86.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:158.60 Median:152.00 stddev:36.00 (23.7%) stddev-sans-first:38.02 03:00:05 INFO - PROCESS | 1674 | Values: 149.0 157.0 138.0 150.0 133.0 142.0 154.0 128.0 251.0 184.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:169.50 stddev:3.60 (2.1%) stddev-sans-first:3.81 03:00:05 INFO - PROCESS | 1674 | Values: 169.0 167.0 168.0 169.0 171.0 170.0 178.0 172.0 167.0 165.0 03:00:05 INFO - PROCESS | 1674 | 03:00:05 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 03:00:05 INFO - PROCESS | 1674 | Values: 68.0 66.0 68.0 67.0 68.0 68.0 68.0 69.0 66.0 68.0 03:00:05 INFO - PROCESS | 1674 | -------- Summary: end -------- 03:00:05 INFO - PROCESS | 1674 | 03:00:06 INFO - TEST-INFO | 1674: exit 0 03:00:06 INFO - TEST-OK | kraken | took 58836ms 03:00:06 INFO - SUITE-END | took 1173s 03:00:06 INFO - Completed test suite (00:19:33) 03:00:06 INFO - javascript benchmark 03:00:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3375.3418083473725, 3447.9311413461846, 3478.5825700915884, 3480.0603836964005, 3521.1901262709575, 5295.258663787073, 5517.062099443095, 5604.271444712187, 5608.10270356743, 5634.7182640867895, 5597.272354593897, 5608.0, 5673.035583950728, 5759.280089988752, 5766.0197766379715, 4168.228877657628, 5069.163588007984, 5082.288479612857, 5104.897902041976, 5253.133233016548, 5175.715335656538, 5200.5059519345605, 5205.895882082356, 5227.111391063529, 5277.8416647500635, 5047.141985862412, 5133.383993920754, 5142.768575414116, 5144.61415393847, 5170.844874653766, 5058.54473097423, 5077.187649976023, 5111.437741848431, 5176.55999240065, 5176.637635365489, 5059.569936555397, 5070.264811602297, 5096.159133742965, 5126.6667666601925, 5161.045206636784, 5439.836804895859, 5533.944660553383, 5550.8057217997175, 5576.693281869496, 5589.74846131921, 5996.97001514994, 6115.847103822395, 6168.074788781674, 6170.7531698732, 6180.660063696495, 6158.353372895826, 6162.630242185483, 6226.844328891769, 6227.87544249117, 6243.56295059346, 5524.0, 5705.0, 5709.486146246858, 5709.68596727184, 5729.598928075036, 6212.223472065992, 6317.46301564363, 6319.873602527924, 6335.239771227482, 6340.239171299381, 6003.759849605967, 6006.789762358297, 6014.338422773491, 6014.8195554133445, 6033.607815491979, 6036.426539478743, 6116.48009919153, 6137.754489820357, 6178.41305076017, 6201.379862013762, 6004.099385092271, 6024.969875150597, 6026.005709058044, 6035.668038257938, 6043.063325184604, 5984.611000284967, 6041.214642096499, 6063.484603808725, 6063.848403789941, 6076.605020673642, 4777.9044419112115, 4843.709377437365, 4850.029994001214, 4922.384701912261, 4938.160512712848, 4250.468691413574, 4358.542353052947, 4363.912721745547, 4384.276594361958, 4413.271810151353, 5575.135853942565, 5594.021046316879, 5600.5799565032785, 5645.181448689917, 5659.603827732019, 6059.303180134253, 6086.969565152146, 6143.941779503916, 6145.692715364304, 6146.739918316824, 5557.055300598908, 5568.7215639217875, 5574.498295153456, 5586.804461843897, 5621.690807005572, 5263.736813159327, 5263.921041184385, 5274.868128296746, 5281.313429254173, 5327.840164795062, 6186.783462578878, 6288.0, 6289.496840252768, 6307.653079080695, 6313.084602732578, 6106.6336019838955, 6108.4807791338235, 6109.908351374822, 6123.9693801532485, 6132.988056970728, 6203.937960620336, 6264.780732674334, 6291.622502649856, 6296.464800492009, 6304.085907543289, 6094.018026236212, 6115.541334399849, 6121.265448146251, 6125.540584456273, 6128.4790792783115, 6143.69941041264, 6167.105769663463, 6168.074788781719, 6208.161898143693, 6210.7515699371515, 5804.883902322014, 5933.851653708692, 5936.495397891227, 6013.925627422496, 6038.758449661965, 5938.762449501972, 5947.524198064231, 5983.132445795422, 5984.910226346521, 6008.939910601013, 5185.844424667266, 5366.651167674088, 5841.94158058431, 5844.824655260349, 5855.063189889597, 5844.357120716632, 5930.199423077829, 5944.613600115978, 6023.789167379121, 6080.361562035922, 5771.884562308646, 5787.074068149076, 5795.681237531976, 5800.738966746422, 5801.970990145022], "value": 5607.267152489567, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.2890515394948, 520.7422325948656, 529.2273281009727, 529.6319058254513, 543.3344153412071, 28387.29031774204, 29965.550516742238, 30071.248218794542, 39053.41419878697, 39138.21723565534, 42571.36142957858, 43707.0, 43948.0, 43961.56038439622, 44018.0, 9487.667931622394, 9677.806443871119, 9685.564149613248, 9697.418154910692, 9704.22366210705, 9386.20217281532, 9526.571304291288, 9528.904710952906, 9539.47532885691, 9541.809163816719, 9505.287103467233, 9551.522423878812, 9573.617055317778, 9580.568874400635, 9583.233341332676, 9482.81034379312, 9530.237580993504, 9545.236381089497, 9574.425534467884, 9583.616655333813, 9408.952955235249, 9492.382995105365, 9496.097870702271, 9496.14534691881, 9498.620055197784, 38328.80835595804, 38604.420933686015, 38772.0, 38826.22347553061, 38876.0, 3195.328980914011, 3235.854386552611, 3378.2061215614312, 3414.5219669246326, 3808.1812410331913, 26941.865290673617, 26958.0, 27369.99476739848, 27849.0, 28029.57955630667, 2719.619253304539, 2733.685626152978, 2767.266674331304, 2794.203651959202, 2808.817426867247, 403126.0, 405428.0, 405565.0, 406023.0, 406109.69451527303, 35027.4745878812, 35778.92663220107, 61678.69160654168, 62173.06740398898, 62230.68884655638, 34975.950721478395, 35098.29803403969, 35207.4718879217, 35250.823745881105, 35320.0, 14723.705525889422, 14878.181700006387, 14880.479183228535, 14882.0, 14898.627534311512, 12590.496380144692, 12659.860612544915, 12677.873221267853, 12691.0, 12710.618681439571, 8043.19568043191, 8060.596970151469, 8066.475679080841, 8146.022477302761, 8146.2261085196815, 3296.093574267096, 3331.4336562784383, 3331.6335203127637, 3337.2658015236607, 3368.494725791151, 959.4483172176027, 972.7957129002989, 1040.0379648824808, 1050.3225419604464, 1081.4754843900703, 27091.864540677172, 27458.0, 27928.0, 29100.0, 29148.0, 1035.1822060572215, 1042.280826229899, 1078.1590359519587, 1117.94969226385, 1119.2445099557929, 14192.716145677028, 14340.426382944564, 14434.845212382985, 14490.565283041526, 14527.636809079646, 627918.0, 638295.0, 643360.0, 645883.0, 646511.0, 56651.15023274654, 57535.0, 57562.136567951515, 57628.0, 57812.42187578155, 1343.4894739998738, 1391.6520869782555, 1465.1062851660265, 1477.6453651123868, 1498.9550313490613, 28249.293767655974, 28481.28796780097, 29187.85406072956, 29619.96330128435, 29681.40637187201, 40215.0, 48989.26516102332, 49560.50439495559, 49607.25589116094, 49775.75112124561, 424859.0, 427026.0, 427171.0, 428316.0, 428729.0, 23060.308190754306, 25256.873715631304, 25650.0, 25815.354616134748, 26173.345666358495, 41651.37522937098, 41852.53516126921, 42390.0, 42534.361984570874, 42969.3554596675, 34733.826330868185, 34852.477212842336, 34893.651063489044, 35744.28511429809, 35912.743053992985, 33791.83104084464, 34226.65773342235, 34378.82810585931, 34498.82750586231, 34963.951081467596, 30118.84940575283, 31716.524252136696, 31797.20506987344, 32226.838865806458, 36020.63979360173, 27228.863855680596, 30617.846910765304, 30648.94292441722, 30679.693203068575, 31031.0, 22941.770582293964, 22974.540509189388, 23193.88403057974, 23737.406564835328, 24044.75955240425], "value": 17739.65845049371, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.7582760774517, 389.79730540119135, 392.09035038710186, 396.90829329433865, 398.48396326756847, 14578.052426592265, 17458.47624571265, 19068.0, 19407.417777466664, 19781.50546236343, 13250.33748312583, 19110.235590576394, 19691.901540492276, 20066.297679581217, 20072.899635501803, 5567.4432556744205, 5659.236003139565, 5861.970690146564, 5866.882662346772, 5875.676837773921, 3419.880304189354, 5713.371529131792, 5727.398623144572, 5791.942080579182, 6026.0, 6326.620402775849, 6347.174867267248, 6358.364163583651, 6361.459275961549, 6362.427381535661, 5604.842135777714, 6148.8462788430425, 6344.238691357021, 6434.485241180694, 6447.194100737408, 5585.301837270341, 5603.075492543725, 5610.18652295419, 5620.4660557247, 5622.297212848394, 23094.845257737044, 24234.757652423425, 24461.755382446125, 24723.134690285755, 25578.616320755205, 1909.2744756992392, 2001.559656875485, 2112.588045331161, 2124.564464284818, 2273.8749368784875, 9301.348905576613, 10013.799724005552, 10040.397576145455, 10185.847212291821, 13309.334533273297, 1637.3450619752075, 1644.5313085770617, 1705.8550023247915, 1726.620143568413, 1729.3082766893217, 83400.0, 103845.0, 111302.44348778205, 111880.0, 112969.43515282453, 9472.95263523678, 11465.828012579652, 11551.595694150665, 11579.76840463186, 11677.0, 13553.186808791505, 14177.291135443187, 14795.482158124414, 14807.111573305636, 14853.628659283604, 7042.330978557029, 8109.432339736162, 8402.957985210034, 8505.404621676547, 8609.784755381166, 8338.0, 8354.707585234488, 8365.539895305696, 8454.027786804596, 8462.280706140047, 4473.642108631302, 4541.227991241497, 4551.180787458289, 4602.286645569942, 4633.305004249322, 1844.9354272600394, 1935.3032908153054, 1943.4946913802514, 1947.1140631013138, 1954.2476146683346, 749.7226026370278, 792.3146478296233, 855.6491838346249, 909.554318383987, 948.9051094890456, 16977.745333820003, 19048.61902761937, 20068.899655501627, 20169.294074707315, 20173.59652806965, 930.4835816122118, 947.6067432015774, 948.4593781544592, 956.5073986896754, 995.64654547636, 10338.741531461623, 10511.737206569744, 10516.159672057513, 10552.577896884039, 10830.404327761891, 591147.0, 591398.0430097822, 599417.0, 609938.0, 610870.0, 118565.40717296358, 119789.40105299592, 119928.40035799766, 121152.78847211415, 122941.77058229482, 1008.7074748323051, 1019.1642852860583, 1039.4750650921237, 1039.5685790397051, 1039.9064084232455, 17421.47735567935, 17727.025013623857, 17752.2011509485, 17784.110794459968, 17969.550761231072, 48472.757636211594, 48639.756801215764, 49299.26051109165, 49934.50065499444, 50299.0, 71632.28367716464, 72568.27431725615, 72599.0, 73276.63361683158, 73779.0, 5974.820755377345, 7868.685252589832, 8054.355651547745, 8057.153998830273, 8090.757277281691, 9132.954335228282, 11816.40917954116, 12124.696882577653, 12161.33112678811, 12374.75250494967, 10452.268341216042, 10666.46667666629, 10730.409827459565, 10885.510152043018, 10932.890671093188, 7655.540667559964, 10330.638427654996, 10575.735606609896, 10683.30558513694, 10706.73233169177, 10674.519646615765, 10794.784104317712, 10878.40168790724, 10921.017108460277, 11110.111191104854, 7931.144787876245, 7961.721339753312, 8034.47775894565, 8045.316148127475, 8391.412601117863, 9448.826705404499, 9565.521723913636, 10475.004874536908, 10833.404162771123, 10971.451427428437], "value": 9223.539349206378, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.1508452470775, 1612.725836607777, 1628.617274940389, 1864.2729335559127, 1976.1700085963903, 7788.922110778891, 8479.109693482189, 8688.826223475535, 8695.826083478327, 8710.433821801595, 8335.54154521501, 8559.315254779618, 8562.186592273723, 8578.056413794478, 8583.227509524142, 2144.1852096203497, 2248.7076680031573, 2277.032261288954, 2286.622707253301, 2355.281639100074, 2446.5351583199135, 2470.962935555968, 2474.604063349864, 2480.255923223035, 2480.516299321633, 2321.7926678127365, 2396.400899775056, 2422.709274887016, 2433.0267892842826, 2446.412860913386, 1915.9137838797287, 2121.5756848630335, 2173.5870184664864, 2337.9532409351887, 2419.9637005444843, 2403.951920961571, 2429.975700242993, 2431.7203521595065, 2434.624437192998, 2435.439848834778, 8423.915760842374, 8505.0, 8507.0, 8523.829523409559, 8525.74422767318, 4991.525805048551, 5168.483151684839, 5181.53366197044, 5193.195054766517, 5211.7394130293715, 3258.413485572596, 3339.7996120232865, 3446.6553344665585, 3457.9827100864336, 3458.066322093046, 987.2891518106951, 989.7921436498344, 1008.591520434218, 1018.332991890313, 1599.8240193578697, 11231.157663175294, 11320.547178112865, 11357.261777984404, 11361.43192840363, 11385.032272256869, 4218.599233072853, 4284.186004659105, 4535.433070866142, 4619.0530941156985, 4621.075784843045, 8747.037825839152, 8821.338399620054, 8837.955810220908, 8850.380473366931, 8851.07063758309, 5107.565856902131, 5139.537441630197, 5157.123289040872, 5160.148575485078, 5181.4300426953005, 4341.587549182823, 4526.7510286933875, 4685.0, 4796.088743138793, 4832.130216561053, 3729.757565758217, 3735.551733791908, 3828.9234215315537, 3842.058695619591, 3844.154286057063, 1073.6564299424265, 1195.49789088583, 1200.5617949448388, 1213.881776300344, 1215.9270443773403, 810.3841080778651, 842.3850589070013, 908.4322298563397, 932.296116432103, 945.8155659646303, 5280.7359632019015, 5463.590230732711, 5668.8095499945475, 5681.289838770153, 5718.656880587178, 439.7625282347561, 462.29500012481236, 930.4556834251903, 940.753501610369, 1062.7343164208949, 3734.8319325630414, 3895.14306852492, 3968.730006397983, 3972.483577134954, 3974.3839704845795, 10106.696799096038, 10513.106385957126, 10560.841587376195, 10568.894311056945, 10589.258751887448, 10162.339447935861, 10353.327033742782, 10494.160467162608, 10606.257561970588, 10641.361518308926, 7834.960825195838, 8018.599070046591, 8121.878171827428, 8147.388945828968, 8159.265666089962, 5595.972020139873, 5704.572157088318, 5752.654840709571, 5772.509336706259, 5994.520438365008, 9420.293477989042, 9440.0, 9733.610655573697, 9827.4103553787, 9837.606495740376, 9396.67111651089, 9844.950775246078, 9853.95073024659, 9940.304178707422, 9957.302988791005, 6738.46092312604, 6828.112345395067, 6918.295559019914, 7235.782926512213, 7465.776026719207, 7686.884696729442, 7746.419018573517, 7772.7668169954995, 8236.50580965144, 8268.131846156308, 7273.636318184175, 7307.3423391894985, 7310.122785265802, 7316.304951029643, 7474.7757567273065, 7241.674124664298, 7253.891191632024, 7291.380232680282, 7331.4134869209265, 7409.66656500448, 6800.761973330909, 6816.113905192492, 6874.415674667509, 6907.792766217021, 6931.44548436134, 4781.737004464788, 4825.710457372569, 4833.879153021062, 4860.781264843161, 4923.803047878045, 4858.4898585648, 4874.147024270667, 4947.727874966911, 4953.0, 4988.152014157529], "value": 4463.242607753697, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.85844789933174, 295.4947040560641, 305.1592861666108, 326.36521964778484, 327.8393587142301, 4874.903791369709, 5777.480026797587, 5789.594728369016, 5848.795292164775, 5856.619319744224, 5075.670081444703, 5276.865473923106, 5430.945690543093, 5687.345955215152, 5749.942500574993, 4305.461817272841, 4327.956720432787, 4336.653067754588, 4355.107203023388, 4371.36615190798, 4335.739855608674, 4346.282863327548, 4351.6953813233085, 4365.2142614329405, 4375.7374557526655, 3573.321068996895, 4133.751974881517, 4282.614564689177, 4290.957090429087, 4313.396124542566, 3563.821808909544, 4268.63090984505, 4302.161078589676, 4306.61240488356, 4313.123791150828, 3679.908002299937, 3708.7589306694977, 3718.7025037997164, 3735.4396840473873, 3773.9245215095543, 4861.781219845116, 5147.7683504242395, 5249.42256351801, 5560.916586251209, 5577.498025177753, 4515.277555591089, 4802.951970480321, 4882.5605695487575, 4921.18800397934, 4940.925886111675, 5388.865278368072, 6061.302950160743, 6086.695665216722, 6107.083937409379, 6117.174181485487, 3184.251700850298, 3810.913889541495, 4064.654504367132, 4093.4269202311702, 4101.425800387948, 5048.29323894655, 5992.880142397127, 6187.5668703190795, 6299.181106456177, 6375.266844312917, 3318.5685860837934, 4128.91742165161, 4138.91722165561, 4154.31453810124, 4180.47744031996, 4050.736702114353, 4174.457320548309, 4193.958060419357, 4209.2423363794815, 4225.408442818008, 3986.282469155522, 4138.896527586834, 4878.414590249122, 4912.463026620827, 4962.751862406865, 6133.0, 6148.938510614837, 6160.8414320333595, 6167.876642467126, 6234.251889773256, 1218.366449446283, 1294.2428679222571, 1371.8559551246976, 1390.4079828596402, 1402.7615305398108, 940.3934462271797, 955.9150364336529, 961.7211008807508, 961.7980224152866, 965.2181732796457, 5577.107662773937, 5626.85932851682, 5688.687122208318, 5688.914666280009, 5689.2603961485565, 5168.328117344721, 5955.910661340084, 6327.114204011442, 6328.525360597881, 6330.012092381862, 5589.888202235932, 5597.132444471114, 5597.552195824405, 5606.859828504238, 5629.521490673339, 2946.7937244392688, 3151.73210277129, 3296.7527435441957, 3325.8004519728893, 3337.3825842219267, 5390.51485366319, 6227.377262273827, 6305.8108256752375, 6529.11856899322, 6619.834504137435, 5906.7637294508595, 6274.905876411857, 6487.351264873475, 6505.186851643544, 6513.609183448913, 5809.564282678816, 5825.94174058254, 6222.222222222132, 6629.320726996467, 6643.701033453506, 5532.391436942014, 6273.686315684289, 6287.96856015717, 6308.306086330408, 6337.4613157882095, 6206.875862482815, 6233.532485063729, 6261.154744109669, 6269.5611307208055, 6287.245530536182, 5736.996025695403, 6257.937420625735, 6659.900101498384, 6760.628165450948, 6773.62745049027, 3753.0617345663586, 4121.907694461031, 4125.958740412558, 4204.747715137101, 4224.809883555309, 5613.971930140323, 5638.646724786104, 5808.5062769665055, 6234.0025595904435, 6417.614943103429, 6101.969490152698, 6146.139540464428, 6173.259208894961, 6182.567220294716, 6205.2553693557065, 5098.923516147335, 5977.193078934288, 6003.219581454383, 6139.2325959255095, 6181.505479561714, 5310.495502927216, 5790.449907258803, 5853.483002309048, 6039.335673075871, 6084.117802918464, 5561.3882472927135, 6164.167837342081, 6175.0, 6194.876102477835, 6212.844678883064, 5078.949210507995, 6032.819015429544, 6055.364186760503, 6087.0, 6122.959096953439], "value": 4411.324433205567, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.6194569095614, 725.9818504537383, 737.9743499211799, 743.3978477433279, 801.6953557648093, 15327.770083448757, 15585.29866156023, 15601.375944962216, 15616.06303621782, 15630.531084067468, 14571.344289506971, 14603.0, 14628.4880029199, 14635.341409636565, 14650.780238296435, 723.7249845058897, 723.9058922340089, 743.8289193485501, 889.4174315823146, 900.1178844732167, 910.9271258299353, 917.156216281023, 917.9770505737343, 921.78338090549, 921.8110287391069, 963.5519483440199, 971.0823272007955, 973.6397532912832, 979.9951000244989, 981.092489447262, 766.1074847802304, 962.7785609309898, 964.2382517810956, 966.3815158298695, 966.4877614864133, 713.0302788208033, 736.9078865141857, 737.1485933746532, 739.1536690489413, 739.8002539314409, 14921.925390373088, 14946.850531494654, 14954.327055282538, 14992.70014599702, 14994.700105997928, 12553.184043037174, 12630.621081367573, 12797.168184068007, 12970.416331265022, 13055.86944130556, 9744.658936937172, 9913.900860991442, 10095.949520252425, 10381.065704086594, 10974.725631859188, 3500.9474857877153, 4010.7593544387432, 4051.372037334218, 4055.2092341993325, 4377.299632058887, 78365.60817195878, 79689.40621187602, 80658.59670701668, 80842.0, 81159.0, 9622.13400793932, 9800.0, 9885.851712224323, 9906.900930990741, 10145.239107067, 22035.449113772283, 22274.554508909732, 22321.330360089225, 22350.441238969157, 22712.091516339162, 6165.630062196283, 6180.072989051589, 6200.627962322275, 6228.159198508143, 6353.96823015882, 4015.357542793139, 4085.571015043437, 4160.0, 4186.937195942063, 4295.978520107379, 3479.8260086995547, 3592.0660628236847, 3614.0, 3624.257027309395, 3641.9817900910325, 643.1767337807614, 654.4894981914115, 658.2627457247818, 662.8972509261072, 664.5613894829486, 507.284728532767, 556.7550277877801, 562.9690367029852, 570.3298624116646, 580.0574067140896, 7356.411487081021, 7572.621368931532, 7808.336291415179, 7922.7227047053075, 7953.602319883983, 538.4023733655639, 555.9583031272671, 557.5762420560403, 570.9457601527848, 574.7873286883796, 3465.8960231193105, 3686.4838922550866, 3733.290674771785, 3761.1349389640536, 3839.3856982882603, 81825.59087204645, 82851.17148828435, 83028.58485707654, 85737.0, 87031.56484217539, 66753.0, 67255.66372168205, 72180.63909680418, 75252.24747752452, 75314.0, 44653.43712970096, 45685.77157114193, 60494.09258861121, 63400.682996585645, 63414.68292658599, 7721.343685786771, 7795.766127016199, 7823.569703666353, 7847.646855891613, 8108.391870609846, 42720.78639606782, 43013.56986430095, 43052.78473607612, 43432.0, 43432.565674342855, 51662.708432289495, 58638.12042819276, 61514.0, 62279.37720622917, 62714.0, 10045.397276163454, 10079.748006299902, 10160.644377446753, 10250.743731406774, 10523.894761052597, 11284.32294062359, 11325.886741132812, 11422.371769552423, 11753.764924701629, 11831.881681183422, 10445.321054131457, 10814.026737593655, 11002.119830413387, 11048.613298534512, 11535.480903359201, 10432.947835260775, 10453.9477302613, 10889.128869690247, 10917.0, 11134.554617815196, 8522.360822938428, 10261.897381026094, 10440.63457778974, 10612.310199836986, 10674.46627668629, 9122.589483473392, 9143.0, 9241.861372079558, 9490.430574165572, 9582.808343833223, 9070.0, 9279.860802087838, 9307.813843723223, 9400.811983760423, 9448.622055117718], "value": 6681.92866774379, "unit": "score"}], "name": "dromaeo_css", "value": 7030.060926860312, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 111.0, 113.0, 112.0, 110.0, 119.0, 113.0, 112.0, 112.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 146.0, 169.0, 118.0, 145.0, 143.0, 117.0, 172.0, 117.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 185.0, 195.0, 256.0, 184.0, 183.0, 185.0, 184.0, 213.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 88.0, 90.0, 57.0, 58.0, 59.0, 81.0, 109.0, 83.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 137.0, 78.0, 77.0, 79.0, 127.0, 79.0, 77.0, 129.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 91.0, 89.0, 84.0, 87.0, 87.0, 134.0, 130.0], "value": 96.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 86.0, 87.0, 87.0, 85.0, 86.0, 91.0, 86.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 60.0, 60.0, 58.0, 59.0, 77.0, 58.0, 57.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 73.0, 71.0, 72.0, 66.0, 67.0, 68.0, 67.0, 68.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 84.0, 83.0, 83.0, 84.0, 84.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 157.0, 138.0, 150.0, 133.0, 142.0, 154.0, 128.0, 251.0, 184.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 168.0, 169.0, 171.0, 170.0, 178.0, 172.0, 167.0, 165.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 67.0, 68.0, 68.0, 68.0, 69.0, 66.0, 68.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1509.7999999999995, "alertThreshold": 2.0}]} 03:00:06 INFO - Return code: 0 03:00:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:00:06 ERROR - # TBPL SUCCESS # 03:00:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:00:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:00:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:00:06 INFO - 2016-04-15 03:00:06.602 screenresolution[1678:9880] starting screenresolution argv=screenresolution get 03:00:06 INFO - 2016-04-15 03:00:06.604 screenresolution[1678:9880] Display 0: 1600x1200x32@60 03:00:06 INFO - 2016-04-15 03:00:06.610 screenresolution[1679:9883] starting screenresolution argv=screenresolution list 03:00:06 INFO - Available Modes on Display 0 03:00:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:00:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:00:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:00:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:00:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:00:06 INFO - Intel Iris: 03:00:06 INFO - Chipset Model: Intel Iris 03:00:06 INFO - Type: GPU 03:00:06 INFO - Bus: Built-In 03:00:06 INFO - VRAM (Dynamic, Max): 1536 MB 03:00:06 INFO - Vendor: Intel (0x8086) 03:00:06 INFO - Device ID: 0x0a2e 03:00:06 INFO - Revision ID: 0x0009 03:00:06 INFO - Displays: 03:00:06 INFO - Display: 03:00:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:00:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:00:06 INFO - Display Serial Number: 1600x1200 60 03:00:06 INFO - Main Display: Yes 03:00:06 INFO - Mirror: Off 03:00:06 INFO - Online: Yes 03:00:06 INFO - Rotation: Supported 03:00:06 INFO - Return code: 0 03:00:06 INFO - Running post-action listener: _resource_record_post_action 03:00:06 INFO - Running post-run listener: _resource_record_post_run 03:00:07 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37660160; Write bytes: 693393408; Read time: 452; Write time: 2022 03:00:07 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 390007296; Write bytes: 384141312; Read time: 29993; Write time: 724 03:00:07 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 35841536; Write bytes: 295849984; Read time: 368; Write time: 1253 03:00:07 INFO - Running post-run listener: _upload_blobber_files 03:00:07 INFO - Blob upload gear active. 03:00:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:00:07 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 03:00:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:00:08 INFO - (blobuploader) - INFO - Open directory for files ... 03:00:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:00:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:00:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:09 INFO - (blobuploader) - INFO - Done attempting. 03:00:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:00:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:00:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:00:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:00:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:00:10 INFO - (blobuploader) - INFO - Done attempting. 03:00:10 INFO - (blobuploader) - INFO - Iteration through files over. 03:00:10 INFO - Return code: 0 03:00:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:00:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:00:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98aa4cfcfca559c4966df58ebd2807c23229311d4827732c8b04ec9b34efa3859402a068daad98becfe93eb39615abefea62fc3c025e407d3941171daca082ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f73a37865fbe782642db015cba7ef4e69044e7ff8a5cde06e795b8b2978f84ea68cc771b0fdec5ce0b9801900a44182168aeedf39ebd1ebaee9619484e1b0ec7"} 03:00:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:00:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:00:10 INFO - Contents: 03:00:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98aa4cfcfca559c4966df58ebd2807c23229311d4827732c8b04ec9b34efa3859402a068daad98becfe93eb39615abefea62fc3c025e407d3941171daca082ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f73a37865fbe782642db015cba7ef4e69044e7ff8a5cde06e795b8b2978f84ea68cc771b0fdec5ce0b9801900a44182168aeedf39ebd1ebaee9619484e1b0ec7"} 03:00:10 INFO - Running post-run listener: copy_logs_to_upload_dir 03:00:10 INFO - Copying logs to upload dir... 03:00:10 INFO - mkdir: /builds/slave/test/build/upload/logs 03:00:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.514543 ========= master_lag: 2.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-15 03:00:12.535074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-04-15 03:00:12.539383) ========= 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.f49zopnQlP/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.fPbTyEq5bi/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98aa4cfcfca559c4966df58ebd2807c23229311d4827732c8b04ec9b34efa3859402a068daad98becfe93eb39615abefea62fc3c025e407d3941171daca082ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f73a37865fbe782642db015cba7ef4e69044e7ff8a5cde06e795b8b2978f84ea68cc771b0fdec5ce0b9801900a44182168aeedf39ebd1ebaee9619484e1b0ec7"} build_url:https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011207 build_url: 'https://queue.taskcluster.net/v1/task/ZlTuurGhQ42bVt6jH5uDOw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/98aa4cfcfca559c4966df58ebd2807c23229311d4827732c8b04ec9b34efa3859402a068daad98becfe93eb39615abefea62fc3c025e407d3941171daca082ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f73a37865fbe782642db015cba7ef4e69044e7ff8a5cde06e795b8b2978f84ea68cc771b0fdec5ce0b9801900a44182168aeedf39ebd1ebaee9619484e1b0ec7"}' ========= master_lag: 5.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 5 secs) (at 2016-04-15 03:00:17.663533) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 03:00:17.663869) ========= 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.f49zopnQlP/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.fPbTyEq5bi/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.005141 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 03:00:17.741409) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 03:00:17.741712) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 03:00:17.742325) ========= ========= Total master_lag: 7.43 =========