builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0011 starttime: 1461271592.67 results: success (0) buildid: 20160421125403 builduid: beac0304bf864a66a34e5b33dbc6f8da revision: 5f5b4ff17b1de061558edcccd2c9e15106359a46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:32.671656) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:32.672121) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:32.672430) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:32.706397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:32.706693) ========= 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.imH7ZjHkbj/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.cybx5DeF12/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-21 13:46:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 150M=0s 2016-04-21 13:46:32 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.139501 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:32.864432) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:32.864767) ========= 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.imH7ZjHkbj/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.cybx5DeF12/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.033662 ========= master_lag: 0.43 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:33.331098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 13:46:33.331427) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 5f5b4ff17b1de061558edcccd2c9e15106359a46 --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 5f5b4ff17b1de061558edcccd2c9e15106359a46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.imH7ZjHkbj/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.cybx5DeF12/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-21 13:46:33,410 truncating revision to first 12 chars 2016-04-21 13:46:33,410 Setting DEBUG logging. 2016-04-21 13:46:33,410 attempt 1/10 2016-04-21 13:46:33,410 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/5f5b4ff17b1d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 13:46:34,602 unpacking tar archive at: fx-team-5f5b4ff17b1d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.440058 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 13:46:34.790376) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:34.790703) ========= script_repo_revision: 5f5b4ff17b1de061558edcccd2c9e15106359a46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:34.791101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:34.791377) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 13:46:34.804645) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-21 13:46:34.805004) ========= /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.imH7ZjHkbj/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.cybx5DeF12/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:46:34 INFO - MultiFileLogger online at 20160421 13:46:34 in /builds/slave/test 13:46:34 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 13:46:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:46:34 INFO - {'append_to_log': False, 13:46:34 INFO - 'base_work_dir': '/builds/slave/test', 13:46:34 INFO - 'blob_upload_branch': 'Fx-Team', 13:46:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:46:34 INFO - 'branch': 'Fx-Team', 13:46:34 INFO - 'buildbot_json_path': 'buildprops.json', 13:46:34 INFO - 'config_files': ('talos/mac_config.py',), 13:46:34 INFO - 'default_actions': ('clobber', 13:46:34 INFO - 'read-buildbot-config', 13:46:34 INFO - 'download-and-extract', 13:46:34 INFO - 'populate-webroot', 13:46:34 INFO - 'create-virtualenv', 13:46:34 INFO - 'install', 13:46:34 INFO - 'run-tests'), 13:46:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:46:34 INFO - 'download_minidump_stackwalk': True, 13:46:34 INFO - 'download_symbols': 'ondemand', 13:46:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:46:35 INFO - 'tooltool.py': '/tools/tooltool.py', 13:46:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:46:35 INFO - '/tools/misc-python/virtualenv.py')}, 13:46:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:46:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:46:35 INFO - 'installer_path': 'installer.exe', 13:46:35 INFO - 'log_level': 'info', 13:46:35 INFO - 'log_name': 'talos', 13:46:35 INFO - 'log_to_console': True, 13:46:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:46:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:46:35 INFO - 'opt_config_files': (), 13:46:35 INFO - 'pip_index': False, 13:46:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:35 INFO - 'cmd': ('bash', 13:46:35 INFO - '-c', 13:46:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:35 INFO - 'enabled': True, 13:46:35 INFO - 'halt_on_failure': False, 13:46:35 INFO - 'name': 'check_screen_resolution'},), 13:46:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:35 INFO - 'cmd': ('bash', 13:46:35 INFO - '-c', 13:46:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:35 INFO - 'enabled': True, 13:46:35 INFO - 'halt_on_failure': False, 13:46:35 INFO - 'name': 'check_screen_resolution'},), 13:46:35 INFO - 'run_cmd_checks_enabled': True, 13:46:35 INFO - 'sps_profile': False, 13:46:35 INFO - 'sps_profile_interval': 0, 13:46:35 INFO - 'suite': 'dromaeojs', 13:46:35 INFO - 'system_bits': '32', 13:46:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:46:35 INFO - 'title': 't-yosemite-r7-0011', 13:46:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:46:35 INFO - 'use_talos_json': True, 13:46:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:46:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:46:35 INFO - 'work_dir': 'build'} 13:46:35 INFO - ##### 13:46:35 INFO - ##### Running clobber step. 13:46:35 INFO - ##### 13:46:35 INFO - Running pre-action listener: _resource_record_pre_action 13:46:35 INFO - Running main action method: clobber 13:46:35 INFO - rmtree: /builds/slave/test/build 13:46:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:46:36 INFO - Running post-action listener: _resource_record_post_action 13:46:36 INFO - ##### 13:46:36 INFO - ##### Running read-buildbot-config step. 13:46:36 INFO - ##### 13:46:36 INFO - Running pre-action listener: _resource_record_pre_action 13:46:36 INFO - Running main action method: read_buildbot_config 13:46:36 INFO - Using buildbot properties: 13:46:36 INFO - { 13:46:36 INFO - "project": "", 13:46:36 INFO - "product": "firefox", 13:46:36 INFO - "script_repo_revision": "production", 13:46:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:46:36 INFO - "repository": "", 13:46:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:46:36 INFO - "buildid": "20160421125403", 13:46:36 INFO - "pgo_build": "False", 13:46:36 INFO - "basedir": "/builds/slave/test", 13:46:36 INFO - "buildnumber": 691, 13:46:36 INFO - "slavename": "t-yosemite-r7-0011", 13:46:36 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:46:36 INFO - "platform": "yosemite_r7", 13:46:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:46:36 INFO - "branch": "fx-team", 13:46:36 INFO - "repo_path": "integration/fx-team", 13:46:36 INFO - "revision": "5f5b4ff17b1de061558edcccd2c9e15106359a46", 13:46:36 INFO - "stage_platform": "macosx64", 13:46:36 INFO - "builduid": "beac0304bf864a66a34e5b33dbc6f8da", 13:46:36 INFO - "slavebuilddir": "test" 13:46:36 INFO - } 13:46:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 13:46:36 INFO - Running post-action listener: _resource_record_post_action 13:46:36 INFO - ##### 13:46:36 INFO - ##### Running download-and-extract step. 13:46:36 INFO - ##### 13:46:36 INFO - Running pre-action listener: _resource_record_pre_action 13:46:36 INFO - Running main action method: download_and_extract 13:46:36 INFO - mkdir: /builds/slave/test/build/tests 13:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:36 INFO - https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:38 INFO - Downloaded 1199 bytes. 13:46:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 13:46:38 INFO - Using the following test package requirements: 13:46:38 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:46:38 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 13:46:38 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 13:46:38 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'jsshell-mac.zip'], 13:46:38 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 13:46:38 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:46:38 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 13:46:38 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 13:46:38 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 13:46:38 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:46:38 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 13:46:38 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:46: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')]} 13:46:38 INFO - https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:46:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:39 INFO - Downloaded 18099879 bytes. 13:46:39 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'] 13:46:39 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 13:46:42 INFO - Return code: 0 13:46:42 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 13:46:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:42 INFO - https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:46:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:46:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:45 INFO - Downloaded 18099879 bytes. 13:46:45 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'] 13:46:45 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 13:46:49 INFO - Return code: 0 13:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:49 INFO - https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:46:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:46:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:46:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:51 INFO - Downloaded 10845952 bytes. 13:46:51 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'] 13:46:51 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 13:46:52 INFO - Return code: 0 13:46: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')]} 13:46:52 INFO - https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:46:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 13:46:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/N50WVc2kS6eNX4SaxAll3Q/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 13:46:58 INFO - Downloaded 109956699 bytes. 13:46:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:58 INFO - mkdir: /builds/slave/test/properties 13:46:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:46:58 INFO - Writing to file /builds/slave/test/properties/build_url 13:46:58 INFO - Contents: 13:46:58 INFO - build_url:https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:46:59 INFO - Running post-action listener: _resource_record_post_action 13:46:59 INFO - Running post-action listener: set_extra_try_arguments 13:46:59 INFO - ##### 13:46:59 INFO - ##### Running populate-webroot step. 13:46:59 INFO - ##### 13:46:59 INFO - Running pre-action listener: _resource_record_pre_action 13:46:59 INFO - Running main action method: populate_webroot 13:46:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:46:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:46:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:46:59 INFO - '--tppagecycles', 13:46:59 INFO - '7'], 13:46:59 INFO - 'tests': ['tsvgm']}}, 13:46:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:46:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:46:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:46:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:46:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:46:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:46:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['damp', 'tps']}, 13:46:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'tests': ['damp', 'tps']}, 13:46:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['dromaeo_dom']}, 13:46:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:46:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['a11yr', 13:46:59 INFO - 'ts_paint', 13:46:59 INFO - 'tpaint', 13:46:59 INFO - 'sessionrestore', 13:46:59 INFO - 'sessionrestore_no_auto_restore', 13:46:59 INFO - 'tabpaint']}, 13:46:59 INFO - 'other-e10s': {'tests': ['a11yr', 13:46:59 INFO - 'ts_paint', 13:46:59 INFO - 'tpaint', 13:46:59 INFO - 'sessionrestore', 13:46:59 INFO - 'sessionrestore_no_auto_restore', 13:46:59 INFO - 'tabpaint']}, 13:46:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:46:59 INFO - 'ts_paint', 13:46:59 INFO - 'tpaint', 13:46:59 INFO - 'sessionrestore', 13:46:59 INFO - 'sessionrestore_no_auto_restore', 13:46:59 INFO - 'tabpaint']}, 13:46:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:46:59 INFO - 'ts_paint', 13:46:59 INFO - 'tpaint', 13:46:59 INFO - 'sessionrestore', 13:46:59 INFO - 'sessionrestore_no_auto_restore', 13:46:59 INFO - 'tabpaint']}, 13:46:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['a11yr', 13:46:59 INFO - 'ts_paint', 13:46:59 INFO - 'tpaint', 13:46:59 INFO - 'sessionrestore', 13:46:59 INFO - 'sessionrestore_no_auto_restore', 13:46:59 INFO - 'tabpaint']}, 13:46:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['a11yr', 13:46:59 INFO - 'ts_paint', 13:46:59 INFO - 'tpaint', 13:46:59 INFO - 'sessionrestore', 13:46:59 INFO - 'sessionrestore_no_auto_restore', 13:46:59 INFO - 'tabpaint']}, 13:46:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['tsvgx', 13:46:59 INFO - 'tsvgr_opacity', 13:46:59 INFO - 'tart', 13:46:59 INFO - 'tscrollx', 13:46:59 INFO - 'cart']}, 13:46:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:46:59 INFO - 'tsvgr_opacity', 13:46:59 INFO - 'tart', 13:46:59 INFO - 'tscrollx', 13:46:59 INFO - 'cart']}, 13:46:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'talos_options': ['--disable-e10s'], 13:46:59 INFO - 'tests': ['tp5o']}, 13:46:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'tests': ['tp5o']}, 13:46:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'talos_options': ['--disable-e10s', 13:46:59 INFO - '--xperf_path', 13:46:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:46:59 INFO - 'tests': ['tp5n']}, 13:46:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:46:59 INFO - 'talos_options': ['--xperf_path', 13:46:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:46:59 INFO - 'tests': ['tp5n']}}, 13:46:59 INFO - 'talos.zip': {'path': '', 13:46:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:46:59 INFO - Running post-action listener: _resource_record_post_action 13:46:59 INFO - ##### 13:46:59 INFO - ##### Running create-virtualenv step. 13:46:59 INFO - ##### 13:46:59 INFO - Running pre-action listener: _resource_record_pre_action 13:46:59 INFO - Running main action method: create_virtualenv 13:46:59 INFO - Creating virtualenv /builds/slave/test/build/venv 13:46:59 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 13:46:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:46:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:46:59 INFO - Using real prefix '/tools/python27' 13:46:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:47:00 INFO - Installing distribute.............................................................................................................................................................................................done. 13:47:02 INFO - Installing pip.................done. 13:47:02 INFO - Return code: 0 13:47:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:02 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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:02 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 13:47:02 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 13:47:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:02 INFO - 'HOME': '/Users/cltbld', 13:47:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:02 INFO - 'LOGNAME': 'cltbld', 13:47:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:02 INFO - 'MOZ_NO_REMOTE': '1', 13:47:02 INFO - 'NO_EM_RESTART': '1', 13:47:02 INFO - 'PAGER': '/bin/cat', 13:47:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:02 INFO - 'PWD': '/builds/slave/test', 13:47:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:02 INFO - 'SHELL': '/bin/bash', 13:47:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:02 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:02 INFO - 'USER': 'cltbld', 13:47:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:02 INFO - 'XPC_FLAGS': '0x0', 13:47:02 INFO - 'XPC_SERVICE_NAME': '0', 13:47:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:03 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 13:47:03 INFO - Cleaning up... 13:47:03 INFO - Return code: 0 13:47:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:47:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:47:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:03 INFO - 'HOME': '/Users/cltbld', 13:47:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:03 INFO - 'LOGNAME': 'cltbld', 13:47:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:03 INFO - 'MOZ_NO_REMOTE': '1', 13:47:03 INFO - 'NO_EM_RESTART': '1', 13:47:03 INFO - 'PAGER': '/bin/cat', 13:47:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:03 INFO - 'PWD': '/builds/slave/test', 13:47:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:03 INFO - 'SHELL': '/bin/bash', 13:47:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:03 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:03 INFO - 'USER': 'cltbld', 13:47:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:03 INFO - 'XPC_FLAGS': '0x0', 13:47:03 INFO - 'XPC_SERVICE_NAME': '0', 13:47:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:03 INFO - Downloading/unpacking psutil>=0.7.1 13:47:03 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 13:47:03 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 13:47:03 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 13:47:03 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 13:47:03 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 13:47:03 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 13:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:47:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:06 INFO - Installing collected packages: psutil 13:47:06 INFO - Running setup.py install for psutil 13:47:06 INFO - building 'psutil._psutil_osx' extension 13:47:06 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 13:47:06 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 13:47:06 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 13:47:06 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 13:47:07 INFO - building 'psutil._psutil_posix' extension 13:47:07 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 13:47:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:47:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:47:07 INFO - ^ 13:47:07 INFO - 1 warning generated. 13:47:07 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 13:47:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:07 INFO - Successfully installed psutil 13:47:07 INFO - Cleaning up... 13:47:07 INFO - Return code: 0 13:47:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:47:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:47:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:07 INFO - 'HOME': '/Users/cltbld', 13:47:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:07 INFO - 'LOGNAME': 'cltbld', 13:47:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:07 INFO - 'MOZ_NO_REMOTE': '1', 13:47:07 INFO - 'NO_EM_RESTART': '1', 13:47:07 INFO - 'PAGER': '/bin/cat', 13:47:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:07 INFO - 'PWD': '/builds/slave/test', 13:47:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:07 INFO - 'SHELL': '/bin/bash', 13:47:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:07 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:07 INFO - 'USER': 'cltbld', 13:47:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:07 INFO - 'XPC_FLAGS': '0x0', 13:47:07 INFO - 'XPC_SERVICE_NAME': '0', 13:47:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:47:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:47:10 INFO - Installing collected packages: mozsystemmonitor 13:47:10 INFO - Running setup.py install for mozsystemmonitor 13:47:10 INFO - Successfully installed mozsystemmonitor 13:47:10 INFO - Cleaning up... 13:47:10 INFO - Return code: 0 13:47:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:47:10 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')]} 13:47:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:10 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')]} 13:47:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:10 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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:10 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 13:47:10 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 13:47:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:10 INFO - 'HOME': '/Users/cltbld', 13:47:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:10 INFO - 'LOGNAME': 'cltbld', 13:47:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:10 INFO - 'MOZ_NO_REMOTE': '1', 13:47:10 INFO - 'NO_EM_RESTART': '1', 13:47:10 INFO - 'PAGER': '/bin/cat', 13:47:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:10 INFO - 'PWD': '/builds/slave/test', 13:47:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:10 INFO - 'SHELL': '/bin/bash', 13:47:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:10 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:10 INFO - 'USER': 'cltbld', 13:47:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:10 INFO - 'XPC_FLAGS': '0x0', 13:47:10 INFO - 'XPC_SERVICE_NAME': '0', 13:47:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:11 INFO - Downloading/unpacking blobuploader==1.2.4 13:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:13 INFO - Downloading blobuploader-1.2.4.tar.gz 13:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:47:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:47:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:15 INFO - Downloading docopt-0.6.1.tar.gz 13:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:47:15 INFO - Installing collected packages: blobuploader, requests, docopt 13:47:15 INFO - Running setup.py install for blobuploader 13:47:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:47:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:47:15 INFO - Running setup.py install for requests 13:47:15 INFO - Running setup.py install for docopt 13:47:15 INFO - Successfully installed blobuploader requests docopt 13:47:15 INFO - Cleaning up... 13:47:16 INFO - Return code: 0 13:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:16 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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:16 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 13:47:16 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 13:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:16 INFO - 'HOME': '/Users/cltbld', 13:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:16 INFO - 'LOGNAME': 'cltbld', 13:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:16 INFO - 'MOZ_NO_REMOTE': '1', 13:47:16 INFO - 'NO_EM_RESTART': '1', 13:47:16 INFO - 'PAGER': '/bin/cat', 13:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:16 INFO - 'PWD': '/builds/slave/test', 13:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:16 INFO - 'SHELL': '/bin/bash', 13:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:16 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:16 INFO - 'USER': 'cltbld', 13:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:16 INFO - 'XPC_FLAGS': '0x0', 13:47:16 INFO - 'XPC_SERVICE_NAME': '0', 13:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ifh8mF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-SeaKWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-nWqv0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-c4eYUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-daxDy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-NlkWof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-7pFFJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:47:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:47:16 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-NFgTYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-gXrirx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-2CaRvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-jqCLQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-fTZVWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-OcyvWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-GBDwVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-HkX6Iw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-vVNTvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:47:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:47:17 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-4ClYvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:47:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:47:18 INFO - Running setup.py install for manifestparser 13:47:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:47:18 INFO - Running setup.py install for mozcrash 13:47:18 INFO - Running setup.py install for mozdebug 13:47:18 INFO - Running setup.py install for mozdevice 13:47:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:47:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:47:18 INFO - Running setup.py install for mozfile 13:47:18 INFO - Running setup.py install for mozhttpd 13:47:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:47:18 INFO - Running setup.py install for mozinfo 13:47:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Running setup.py install for mozInstall 13:47:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Running setup.py install for mozleak 13:47:19 INFO - Running setup.py install for mozlog 13:47:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Running setup.py install for moznetwork 13:47:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Running setup.py install for mozprocess 13:47:19 INFO - Running setup.py install for mozprofile 13:47:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:47:19 INFO - Running setup.py install for mozrunner 13:47:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:47:20 INFO - Running setup.py install for mozscreenshot 13:47:20 INFO - Running setup.py install for moztest 13:47:20 INFO - Running setup.py install for mozversion 13:47:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:47:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:47:20 INFO - Cleaning up... 13:47:20 INFO - Return code: 0 13:47:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:20 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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:20 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 13:47:20 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 13:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:20 INFO - 'HOME': '/Users/cltbld', 13:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:20 INFO - 'LOGNAME': 'cltbld', 13:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:20 INFO - 'MOZ_NO_REMOTE': '1', 13:47:20 INFO - 'NO_EM_RESTART': '1', 13:47:20 INFO - 'PAGER': '/bin/cat', 13:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:20 INFO - 'PWD': '/builds/slave/test', 13:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:20 INFO - 'SHELL': '/bin/bash', 13:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:20 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:20 INFO - 'USER': 'cltbld', 13:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:20 INFO - 'XPC_FLAGS': '0x0', 13:47:20 INFO - 'XPC_SERVICE_NAME': '0', 13:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:47:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-4QaWkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Xl1jyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-w839lY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5eHchb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-D6pcxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-qpMOop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-uHFrCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-8XJUsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-3lbNvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-6CSYLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:47:21 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)) 13:47:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:47:21 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-NBh4K4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:47:22 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)) 13:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:47:22 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-E_bot3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:47:22 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)) 13:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:47:22 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-tIwcKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:47:22 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)) 13:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:47:22 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Rmo56F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:47:22 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)) 13:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:22 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-GHU8uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:22 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)) 13:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:47:22 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ezH3ml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:47:22 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)) 13:47:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:47:22 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Il10Ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:47:22 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)) 13:47:22 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)) 13:47:22 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)) 13:47:22 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)) 13:47:22 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)) 13:47:22 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)) 13:47:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:25 INFO - Downloading blessings-1.6.tar.gz 13:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:47:25 INFO - Installing collected packages: blessings 13:47:25 INFO - Running setup.py install for blessings 13:47:25 INFO - Successfully installed blessings 13:47:25 INFO - Cleaning up... 13:47:25 INFO - Return code: 0 13:47:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:47:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:25 INFO - Reading from file tmpfile_stdout 13:47:25 INFO - Current package versions: 13:47:25 INFO - blessings == 1.6 13:47:25 INFO - blobuploader == 1.2.4 13:47:25 INFO - docopt == 0.6.1 13:47:25 INFO - manifestparser == 1.1 13:47:25 INFO - mozInstall == 1.12 13:47:25 INFO - mozcrash == 0.17 13:47:25 INFO - mozdebug == 0.1 13:47:25 INFO - mozdevice == 0.48 13:47:25 INFO - mozfile == 1.2 13:47:25 INFO - mozhttpd == 0.7 13:47:25 INFO - mozinfo == 0.9 13:47:25 INFO - mozleak == 0.1 13:47:25 INFO - mozlog == 3.1 13:47:25 INFO - moznetwork == 0.27 13:47:25 INFO - mozprocess == 0.22 13:47:25 INFO - mozprofile == 0.28 13:47:25 INFO - mozrunner == 6.11 13:47:25 INFO - mozscreenshot == 0.1 13:47:25 INFO - mozsystemmonitor == 0.0 13:47:25 INFO - moztest == 0.7 13:47:25 INFO - mozversion == 1.4 13:47:25 INFO - psutil == 3.1.1 13:47:25 INFO - requests == 1.2.3 13:47:25 INFO - wsgiref == 0.1.2 13:47:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:25 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')]} 13:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:25 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')]} 13:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:25 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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:25 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 13:47:25 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 13:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:25 INFO - 'HOME': '/Users/cltbld', 13:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:25 INFO - 'LOGNAME': 'cltbld', 13:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:25 INFO - 'MOZ_NO_REMOTE': '1', 13:47:25 INFO - 'NO_EM_RESTART': '1', 13:47:25 INFO - 'PAGER': '/bin/cat', 13:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:25 INFO - 'PWD': '/builds/slave/test', 13:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:25 INFO - 'SHELL': '/bin/bash', 13:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:25 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:25 INFO - 'USER': 'cltbld', 13:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:25 INFO - 'XPC_FLAGS': '0x0', 13:47:25 INFO - 'XPC_SERVICE_NAME': '0', 13:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 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)) 13:47:25 INFO - Cleaning up... 13:47:25 INFO - Return code: 0 13:47:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:47:25 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')]} 13:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:25 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')]} 13:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:25 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 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:25 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 13:47:25 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 13:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:25 INFO - 'HOME': '/Users/cltbld', 13:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:25 INFO - 'LOGNAME': 'cltbld', 13:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:25 INFO - 'MOZ_NO_REMOTE': '1', 13:47:25 INFO - 'NO_EM_RESTART': '1', 13:47:25 INFO - 'PAGER': '/bin/cat', 13:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:25 INFO - 'PWD': '/builds/slave/test', 13:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:25 INFO - 'SHELL': '/bin/bash', 13:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:25 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:25 INFO - 'USER': 'cltbld', 13:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:25 INFO - 'XPC_FLAGS': '0x0', 13:47:25 INFO - 'XPC_SERVICE_NAME': '0', 13:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:26 INFO - Downloading/unpacking jsonschema 13:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:47:28 INFO - Downloading/unpacking functools32 (from jsonschema) 13:47:28 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 13:47:28 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 13:47:28 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 13:47:28 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 13:47:28 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 13:47:28 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 13:47:29 INFO - Downloading functools32-3.2.3-2.tar.gz 13:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:47:29 INFO - warning: no files found matching '*.txt' 13:47:29 INFO - no previously-included directories found matching 'build' 13:47:29 INFO - no previously-included directories found matching 'dist' 13:47:29 INFO - no previously-included directories found matching '.git*' 13:47:29 INFO - Installing collected packages: jsonschema, functools32 13:47:29 INFO - Running setup.py install for functools32 13:47:29 INFO - warning: no files found matching '*.txt' 13:47:29 INFO - no previously-included directories found matching 'build' 13:47:29 INFO - no previously-included directories found matching 'dist' 13:47:29 INFO - no previously-included directories found matching '.git*' 13:47:29 INFO - Successfully installed jsonschema functools32 13:47:29 INFO - Cleaning up... 13:47:29 INFO - Return code: 0 13:47:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107800a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107810420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf25663b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, '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.imH7ZjHkbj/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.cybx5DeF12/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:47:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:47:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:47:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:29 INFO - 'HOME': '/Users/cltbld', 13:47:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:29 INFO - 'LOGNAME': 'cltbld', 13:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:29 INFO - 'MOZ_NO_REMOTE': '1', 13:47:29 INFO - 'NO_EM_RESTART': '1', 13:47:29 INFO - 'PAGER': '/bin/cat', 13:47:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:29 INFO - 'PWD': '/builds/slave/test', 13:47:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:29 INFO - 'SHELL': '/bin/bash', 13:47:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:47:29 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:47:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:29 INFO - 'USER': 'cltbld', 13:47:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:29 INFO - 'XPC_FLAGS': '0x0', 13:47:29 INFO - 'XPC_SERVICE_NAME': '0', 13:47:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:29 INFO - Downloading/unpacking flake8 13:47:29 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 13:47:29 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 13:47:29 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 13:47:29 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 13:47:29 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 13:47:29 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 13:47:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:47:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:47:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:47:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:47:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:47:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:47:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:47:33 INFO - no previously-included directories found matching 'docs/_build' 13:47:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:47:33 INFO - Running setup.py install for pep8 13:47:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:47:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:47:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:47:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:47:33 INFO - no previously-included directories found matching 'docs/_build' 13:47:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:47:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:47:33 INFO - Cleaning up... 13:47:33 INFO - Return code: 0 13:47:33 INFO - Running post-action listener: _resource_record_post_action 13:47:33 INFO - Running post-action listener: _start_resource_monitoring 13:47:33 INFO - Starting resource monitoring. 13:47:33 INFO - ##### 13:47:33 INFO - ##### Running install step. 13:47:33 INFO - ##### 13:47:33 INFO - Running pre-action listener: _resource_record_pre_action 13:47:33 INFO - Running main action method: install 13:47:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:34 INFO - Reading from file tmpfile_stdout 13:47:34 INFO - Detecting whether we're running mozinstall >=1.0... 13:47:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:47:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:47:34 INFO - Reading from file tmpfile_stdout 13:47:34 INFO - Output received: 13:47:34 INFO - Usage: mozinstall [options] installer 13:47:34 INFO - Options: 13:47:34 INFO - -h, --help show this help message and exit 13:47:34 INFO - -d DEST, --destination=DEST 13:47:34 INFO - Directory to install application into. [default: 13:47:34 INFO - "/builds/slave/test"] 13:47:34 INFO - --app=APP Application being installed. [default: firefox] 13:47:34 INFO - mkdir: /builds/slave/test/build/application 13:47:34 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'] 13:47:34 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 13:48:08 INFO - Reading from file tmpfile_stdout 13:48:08 INFO - Output received: 13:48:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:48:08 INFO - Running post-action listener: _resource_record_post_action 13:48:08 INFO - ##### 13:48:08 INFO - ##### Running run-tests step. 13:48:08 INFO - ##### 13:48:08 INFO - Running pre-action listener: _resource_record_pre_action 13:48:08 INFO - Running main action method: run_tests 13:48:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:48:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:48:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:48:08 INFO - 2016-04-21 13:48:08.418 screenresolution[1646:7568] starting screenresolution argv=screenresolution get 13:48:08 INFO - 2016-04-21 13:48:08.421 screenresolution[1646:7568] Display 0: 1600x1200x32@50 13:48:08 INFO - 2016-04-21 13:48:08.427 screenresolution[1647:7570] starting screenresolution argv=screenresolution list 13:48:08 INFO - Available Modes on Display 0 13:48:08 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 13:48:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:48:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:48:08 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 13:48:08 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 13:48:08 INFO - Graphics/Displays: 13:48:08 INFO - Intel Iris: 13:48:08 INFO - Chipset Model: Intel Iris 13:48:08 INFO - Type: GPU 13:48:08 INFO - Bus: Built-In 13:48:08 INFO - VRAM (Dynamic, Max): 1536 MB 13:48:08 INFO - Vendor: Intel (0x8086) 13:48:08 INFO - Device ID: 0x0a2e 13:48:08 INFO - Revision ID: 0x0009 13:48:08 INFO - Displays: 13:48:08 INFO - Display: 13:48:08 INFO - Resolution: 1600 x 1200 @ 50 Hz 13:48:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:48:08 INFO - Display Serial Number: 1600x1200 50 13:48:08 INFO - Main Display: Yes 13:48:08 INFO - Mirror: Off 13:48:08 INFO - Online: Yes 13:48:08 INFO - Rotation: Supported 13:48:08 INFO - Return code: 0 13:48:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:48:08 INFO - Python 2.7.3 13:48:08 INFO - Return code: 0 13:48:08 INFO - grabbing minidump binary from tooltool 13:48: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')]} 13:48:08 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 0x10769fe00>, '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 0x1074c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10769ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:48:08 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 13:48:08 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 13:48:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:48:08 INFO - Return code: 0 13:48:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:48:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:48:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:48:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:48:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:48:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:48:09 INFO - Return code: 0 13:48:09 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/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:48:09 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/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.imH7ZjHkbj/Render', 13:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:09 INFO - 'HOME': '/Users/cltbld', 13:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:09 INFO - 'LOGNAME': 'cltbld', 13:48:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:48:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:09 INFO - 'MOZ_NO_REMOTE': '1', 13:48:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:48:09 INFO - 'NO_EM_RESTART': '1', 13:48:09 INFO - 'PAGER': '/bin/cat', 13:48:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:09 INFO - 'PWD': '/builds/slave/test', 13:48:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:09 INFO - 'SHELL': '/bin/bash', 13:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cybx5DeF12/Listeners', 13:48:09 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 13:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:09 INFO - 'USER': 'cltbld', 13:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:09 INFO - 'XPC_FLAGS': '0x0', 13:48:09 INFO - 'XPC_SERVICE_NAME': '0', 13:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:09 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/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:48:10 INFO - mozversion application_buildid: 20160421125403 13:48:10 INFO - mozversion application_changeset: 5f5b4ff17b1de061558edcccd2c9e15106359a46 13:48:10 INFO - mozversion application_display_name: Nightly 13:48:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:48:10 INFO - mozversion application_name: Firefox 13:48:10 INFO - mozversion application_remotingname: firefox 13:48:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:48:10 INFO - mozversion application_vendor: Mozilla 13:48:10 INFO - mozversion application_version: 48.0a1 13:48:10 INFO - mozversion platform_buildid: 20160421125403 13:48:10 INFO - mozversion platform_changeset: 5f5b4ff17b1de061558edcccd2c9e15106359a46 13:48:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:48:10 INFO - mozversion platform_version: 48.0a1 13:48:10 INFO - using testdate: 1461271690 13:48:10 INFO - actual date: 1461271690 13:48:10 INFO - starting webserver on 'localhost:49270' 13:48:10 INFO - SUITE-START | Running 2 tests 13:48:10 INFO - TEST-START | dromaeo_css 13:48:10 INFO - operating with platform_type : mac_ 13:48:10 INFO - Initialising browser for dromaeo_css test... 13:48:10 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp8x2L7p/profile http://localhost:49270/getInfo.html) 13:48:12 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 13:48:12 INFO - PROCESS | 1665 | colorDepth:24 13:48:12 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 13:48:12 INFO - PROCESS | 1665 | __metrics 13:48:12 INFO - PROCESS | 1665 | 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" 13:48:12 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:48:13 INFO - TEST-INFO | 1665: exit 0 13:48:13 INFO - Browser initialized. 13:48:13 INFO - Running cycle 1/1 for dromaeo_css test... 13:48:13 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp8x2L7p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:48:25 INFO - PROCESS | 1667 | RSS: Main: 238825472 13:48:25 INFO - PROCESS | 1667 | 13:49:16 INFO - PROCESS | 1667 | 1461271756369 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] 13:51:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 13:51:28 INFO - PROCESS | 1667 | RSS: Main: 325890048 13:51:28 INFO - PROCESS | 1667 | 13:54:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 13:54:31 INFO - PROCESS | 1667 | RSS: Main: 374444032 13:54:31 INFO - PROCESS | 1667 | 13:57:33 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 13:57:34 INFO - PROCESS | 1667 | RSS: Main: 378208256 13:57:34 INFO - PROCESS | 1667 | 14:00:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 14:00:37 INFO - PROCESS | 1667 | RSS: Main: 375083008 14:00:37 INFO - PROCESS | 1667 | 14:03:40 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 14:03:41 INFO - PROCESS | 1667 | RSS: Main: 344502272 14:03:41 INFO - PROCESS | 1667 | 14:06:43 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 14:06:44 INFO - PROCESS | 1667 | RSS: Main: 441159680 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | __start_tp_report 14:06:44 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 14:06:44 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 14:06:44 INFO - PROCESS | 1667 | |i|pagename|runs| 14:06:44 INFO - PROCESS | 1667 | |0;dojo.html;3367.1582104473882;3453.326601312745;3509.8420571074307;3513.8067406292644;3539.557555305587;5703.40114288;5755.712214389274;5852.795152169675;5853.209816674747;5854.560907931911;5737.684427356494;5765.827025189241;5801.47786699197;5811.564132690044;5891.204687367215;4068.41008053832;4610.97694511527;5198.064348417292;5198.350206224222;5207.218917162418;5242.501962313574;5260.447652996438;5275.472452754713;5278.683279003253;5288.444713305105;710.5919546290279;5184.170532714747;5298.1522956327;5298.36419629645;5345.091334473149;5162.303089082963;5198.012377648235;5200.3759548854005;5216.582673386158;5227;5140.820071297524;5158.045761534128;5161.638685292031;5169.405518365361;5213.791448342062;5596.916046259309;5632.915506267409;5641.605087643867;5644.153376993443;5716.314042314907;6166.599171053867;6203.2245969253845;6250.031245157008;6281.434670879597;6285.591436556655;6145.600535965193;6181.876362472771;6190.473809726179;6203.565750397474;6210.937890621081;5645.040343141717;5659.915101273484;5665.093585026376;5667.1499275108235;5684.062129748587;6240.563160578716;6284.842878928063;6297.905531417033;6300.905486417708;6317.399847014526;6153.815385538441;6161.229846269216;6175.96912015437;6187.752489900444;6189.195404597373;6105.52039974972;6234.875302493925;6309.968450157719;6334.461570766536;6344.555881088372;6137.324894261627;6154;6201.317855035943;6217.378262173836;6222.751089956441;6225.844353891189;6235.812925612239;6241.407066328782;6262.624242545371;6273.7176827042895;4830.492798256203;4913.533214344631;4921.163402221631;4925.187344088257;4962.652614317035;4420.7126536775;4428.114377124523;4449.488308844524;4477.485089214717;4502.054568540643;5611.719414029282;5637.295338082739;5706.857328566819;5732.398098199712;5735.311762588516;6245.5628106033055;6303.086052522359;6304.810855674337;6323.27282362525;6342.429181373698;5592.468715472024;5657.12314591239;5672.602917795714;5673.177389278614;5721.170430287585;5290.7354632269;5358.785648574091;5363.410024897258;5370.677759334452;5378.2470454136455;6209.937900620936;6265.185525881698;6322.462590679844;6333.240011198685;6343.048542718537;5398.973005134949;6189.659568723763;6305.432511074026;6322.114903913548;6344.777932772331;6226.066090086523;6285.088662144055;6313.715882785377;6331.240251169889;6335.65153916539;6161.691915404302;6234.064890266496;6235.189425374672;6245.6252624842655;6246.3441338660605;6233.719482623203;6284.465820405317;6310.7791227306825;6315.90526142099;6316.368363163647;5923.911141332797;5986.610870293417;5992;6049.485793707584;6145.8156255312415;6008.339082700987;6062;6068.150458935665;6083.452489275809;6086.6348019119;5904.734286957011;5928.733207005609;5945.40545945402;5951.821445356646;5954.10688396744;5899.557533184942;6023.216981792339;6110.205673262448;6120.938790612036;6143.201383820075;5951.137085122719;5961.2846458425265;5977.850553736017;5981.611195272293;5992.820215393545 14:06:44 INFO - PROCESS | 1667 | |1;ext.html;477.53440395614257;498.391961806596;514.1696160700468;514.4726655178442;534.8288547664749;29087.41825163498;36094.639053609455;37473.250534989354;37514.24971500568;37535.62464375355;38660.22679546408;39266.41100383497;39308.820735377914;39469;39495.60504394955;8483.787905302355;8656.740297791077;9351.859722104175;9563.617455301779;9597.280203984694;11218.214724969255;11557.133215008866;11589.36258505782;11593.6521904343;11601.825972610417;11185.944070279636;11389.088872890148;11394.943025284862;11399.544018239261;11427.42862856858;8591.140885911422;11311.604093856758;11346.48940797662;11481.368524731137;11653.941730291379;11233.438328083563;11376.033037191848;11391.974722274952;11401.087912967027;11415.54337826486;36738.44892326617;36887.63112368869;37002.62997370046;37020;37055.62944370549;3265.9346813063607;3287.211069343364;3380.1042723678247;3388.8644454221803;3586.9700670438247;27945.720542794515;28600;28634.85682571574;28658.570121448196;29064.1108327958;2399.880005999693;2716.4702882937836;2726.740959608834;2787.3310405502734;2810.6065150878894;404597.977010116;456183;460001;460363;460960;35041.9189217562;58647.70676146592;58694;59364;59442.702786486654;35388.292234155175;35562.64437355593;35697.28605427929;35732.285354293286;35741.10647233788;14230.003899727128;14448.988570799944;14476.493322733653;14476.638084047772;14484.927575362055;10990.615328463466;11208.383538905275;11219;11230.494627741771;12105;7455.478116531899;7979.601019948979;8029.156938521488;8043.356531477468;8078.23256790604;3258.8207648579555;3320.1201681554408;3320.335932813399;3325.48454989477;3347.6652334766327;941.8398872191743;984.5913945712449;1027.2141811514198;1080.1466841195368;1092.9508172132114;23526.64710029351;28106.016289429772;28314.85842570774;28673.856630716713;29496;1086.2884810449168;1089.7657003744232;1114.9888501114885;1207.3661327802974;1213.3266037349179;13960.790588141186;14656.267186640625;14710.117392956457;14760.48338308154;15109.39562417491;602429;606349;612688.9365553143;613111;613865;53008.93982120336;54139.729301354026;56871.146932796044;57804;57939.71030144822;1297.7664020476216;1469.5591322603177;1470.624990627383;1472.63184203949;1524.1540944775534;22117.166671622323;27320.726792731817;27692.723072769015;28300.292492687848;28484.287892802844;45247.41634042924;45361.54638453573;45815.77092114518;45839;45916.311255331864;410738;416733;417438;417450;418003.9099804603;22639.660405093608;25343.873280633477;25715.09997150091;25858.870705647103;26155;41088.794556028224;41102;41367;41500.79249603733;41960.160796784505;34387.14032149216;34699.306013879075;34734.47898281578;35697.82151089228;35798.28403431969;29980;33811.64753409836;35818.64181358153;36333.63666363303;36383.27233455369;33951.641934323336;34123.31753364869;34515.82742086273;35052;35575.46636800398;29691.703082969758;31002.914897978462;31149;31278.218044548157;31710.68289317077;23624.29127126189;23788.762112379347;23911.92396342134;24135.396615084763;24216.757832421452 14:06:44 INFO - PROCESS | 1667 | |2;jquery.html;394.4359565820876;395.57870867526094;396.54001358424233;397.4515169066689;407.86132714876953;11714.177192999532;19694.916779577117;19766.60466790665;20421.48946276344;20469.590608187827;16189.60384703455;16571.58571035725;18946.147423365946;20184.69722954157;20613.27853525127;5541;6118.11287363332;6170.259568851744;6177.289611694645;6179.412955769195;5972.0444728843395;6029.035354343306;6035.939640603582;6039.426254505837;6082.057281121433;4148.8340466381305;4398.362237475564;6233.906491402633;6609.4051535361805;6617.437517810992;6489.091527186198;6574.441172500343;6586.176727909012;6601.306862779387;6609.867802643968;5555.138953462219;5731.369549349568;5760.452756988079;5770.653760774325;5773;21169.68245476319;23293.83530823452;23771.88114059436;25857.707114644192;26691.599626005627;2327.6043072677685;2601.2846467221475;2890.3930174663346;2985.805922615023;3079.2148002259505;14624.634384140376;14815.48145814902;14820.962532622721;14935.402583896632;15313.23433828304;1682.1757338903972;1683.9326426942907;1734.3062774890018;1894.526368407898;2023.0390564481754;90514.09485905123;105616.4719176407;109206;110374;112213;11246.831297530543;11420.344050112853;11528.711782205344;11570.305781653127;11678.532858685558;14692.19192944377;14804.925975370053;14837;14840.62898427548;14861.851381486264;8235.21765432283;8393.202645748644;8401.579921003926;8489.405741598152;8508.234258916604;8139.4709343892455;8143.22697978106;8145.877811832827;8257.587120643944;8348.039975402786;4196.118815048813;4499.752513611717;4539.205639013159;4563.429571303587;4616.699914505546;1791.2834866053422;1797.451777207938;1854.7866995295444;1976.2589029114083;1979.3962841333243;845.4166625028711;862.9870551941726;944.0417975754615;996.4718699089494;1025.6102680981328;16363.60909322697;17165.055921924166;18117;18408;19723.408297750804;978.1538968791943;984.4831463481586;1003.8996100390049;1011.3375738891039;1033.9689809305733;10390.376577405228;10564;10622.521986510626;10639.627613033506;10661.413622250695;539801;542876;543842;557599;560749;123337;124322;125490.11764823535;126060;127963.72036279704;1019.1591936652144;1020.3316827478013;1035.471909326249;1042.4839704346398;1044.2116202267239;13932.58202253934;17794.669978602145;18073.728894066335;18195.272189112817;18379;34180.658193417745;39911.2017759649;46373.07253854971;46424.53575464337;48544.786380340774;67945.66027169832;68993;69546.65226674036;69726.25684357932;71576;7750.914871917823;7891.487053341514;8239.423240373117;8241.50550966944;8403.495790252604;9496.667616633385;12603.747925041265;12680.429380677708;13030.218186908816;13080.542181023618;10476;10582.994615511514;10771.569137234423;11425.542978281108;11447.313161210353;10890.61882834097;10989.835152472559;11226.270292430965;11460.255083419552;11532.827007594726;11338.60314888975;11395.316281023164;11577.247478913843;11585.116260495339;11702.765944681229;8112.43212975086;8273.669053238043;8307.750767476986;8469.983601967804;8556.358273129415;10931.781364372828;11305.095592352756;11346.489407976826;11371.20401571882;11617.0706343494 14:06:44 INFO - PROCESS | 1667 | |3;mootools.html;1433.605758416436;1607.6463178100819;1608.163754847479;1920.087958219845;2050.84618653601;7946.4834785738885;8700.216980471756;8945.64217431302;8951.418157819737;8953.015168331478;8496.065432802403;8752.124787521261;8757.343199260065;8758.474491530513;8762.474251544896;2166.2959538150085;2386.9283921482383;2464.408541949928;2484.7391023942405;2494.2641920633364;2489.55188066148;2519.2064499682633;2528.2415275417393;2530.6457096006484;2531.582288922335;2428.7206971198275;2494.775470207681;2495.6007038873786;2498.088197807805;2500.599904015358;2157.719496465465;2379.6787433696577;2454.6808914841044;2462.827602067856;2464.691913510811;2462.9137980170785;2484.962725559118;2511.573032584465;2511.5981442969037;2514.2582938033324;8653.134686531359;8720.127987201293;8723.38936274461;8727.34544909128;8731.388802783807;5326.813561525367;5439.292891924064;5443.482869127427;5450.809221677222;5475.521609165543;3517.859285628572;3551.094470909927;3562.09166662502;3593.2094939113354;3598.7660802047785;928.535732133933;1017.4963393120379;1029.4646783672524;1041.1930753665895;1042.9634962776267;11406.258593191416;11610.129240307011;11631.418429078512;11645;11711.765764684658;4289.399484072233;4691.976540117277;4728.338032675429;4961.627877909171;4972.105021096238;10110.494475276208;10116.241281903887;10118;10121.595136194617;10140.746481338025;5451.863703407367;5453.536449401766;5461.1262198048125;5463.754131064112;5492.725363731877;4430.62339701129;4601.217792975202;4820.9517904820505;4925.359703238556;4946.159152944008;4005.7997100144876;4019.31671615826;4134.586541345901;4154.958450415457;4156.06488540076;1356.8236129303127;1372.1286982765816;1373.807666926631;1376.3875075591466;1384.508499482669;902.6479672927563;917.3578495053489;939.7016472182688;940.3135710931059;968;5776.104703770922;5902.793402230901;5920.259967504062;5963.463288304073;5984.3417224105315;450.28629622048766;451.52589780730756;454.3820404250217;465.1650287733526;486.2924444932614;3877.2245550889934;3910.081130934228;3957.683385329167;3973.662238709684;4054.351303791438;10261;10625.734356641145;10689.83965240522;10775.892241077645;10777.353358798497;10602.522886470128;10704.946475267574;10705.30415523004;10728.946355268174;10766.461676916122;7793.12964888434;8183.222593853575;8251.844741736162;8255.463394879194;8256.380771442165;5487.6158668892795;5565.7217139143695;5646.858828529155;5654.208410822571;5741.598088133791;9702.6118955241;9722.27082968766;9875.160611348116;9924.950375248078;9964.55159517838;9840.852387214054;10085.546150423103;10085.949570252395;10181.440020798927;10206.979302069734;7484.25157484269;7496.325330720261;7507.399408047234;7569.697212111454;7570.977917981002;8109.635066421907;8131.715389961323;8225.83548329042;8237;8237.958810205912;7026.8945965809535;7316.122065352191;7341.853162936818;7597.506162099446;7613.809654758676;7319.158296795937;7415.295546923034;7416.0729908761405;7574.280443357872;7581.128170260491;7065.929340706733;7096;7128;7144.85710285781;7153.60655163971;4839.903201935871;4977.328060711758;5039.974800125976;5051.989602079672;5059.5952323814745;4978.576820970114;5034.974825125851;5041.6470847040355;5103.5661968733075;5242.633015688865 14:06:44 INFO - PROCESS | 1667 | |4;prototype.html;289.6755633690267;290.7412402961366;320.2538086259017;321.4567381125896;332.1347888749808;5798.768049278029;5850.3564607893095;5877.853053673661;5883.646981181121;5930.822075337746;5822.214001109859;5839.9124013139835;5853.95111710901;5855.160247280076;5871.911921321183;3806.676432503227;4162.354835000579;4247.8088486018205;4306.74159550428;4399.350243658628;4360.193364227613;4404.317330813729;4427.325319984421;4428.335749637548;4438.313440892456;4399.252127138394;4415.757133357664;4416.646668266547;4418.734875907456;4423.137488189805;3730.645588669072;4164.666826653875;4392.824287028547;4406.272964960777;4450.332450132473;3239.2549713566013;3781.4327850822324;3809.5047643806406;3814.923701525954;3815.585051379028;5606.271184745957;5606.271184745957;5610;5610.625396777816;5619.213310136584;4254.595983325496;4757.381540399726;4905.8773530661665;5052.545270925634;5145.434002259748;5880.382559831199;6045.637261764265;6077.483413909823;6094.238220222473;6116.3577824328695;3480.8085555294447;3753;3789.5073640426845;4217.472815898012;4235.9364609530885;5754.913676294859;5787.13193021046;5914.846604912044;6454.419102280817;6511.0558968949235;3870.8838734838;4174.5616710244985;4284.421603083606;4320.675949303814;4340.934885976713;4384.232759267115;4384.605385515319;4408.977955110204;4414.911701765946;4422.977885110554;4916.287138364989;5062.594992400599;5074.771635276422;5077.517635824591;5079.974600126976;5200.869978250574;5557.166425036196;6107.511399088063;6165.722542485599;6181.690915454209;1238.158052524292;1502.481643832876;1516.7649014402784;1516.8168828313933;1521.361028368088;854.7393045121298;950.4914870544226;975.2685485885586;989.5250279865704;1022.2282176956351;4750.61995040396;4896.338994235733;5006.749662516932;5757.877213943241;5801.593888427854;5946.375630558729;6455.193100862392;6465.321141280192;6466.708998095097;6495.745286837129;5523.6685798852195;5579.19101730255;5751.626144300607;5764.221830052889;5766.567507436959;2991.4316279906748;3073.863664752975;3244.740420766333;3286.4413049781597;3338.4825352070466;6465.418112369815;6743.966280168666;6750.797476075725;6769.32306769329;6783.590666067096;5723.6851973141875;6567.719294737481;6599.868002639921;6645.966770166118;6650.938209155177;5902.852428689232;6540.771073012422;6710.261871194165;6728.510451133112;6743.157105361928;6428.967855160694;6429.582077165156;6440.645764482999;6452.419282264431;6452.677366131581;6316.273628532777;6326.746930122928;6349.428551430394;6359.236891573041;6363.372877459803;6487.286398496256;6669.1330127083165;6690.29751876046;6716.3955244028275;6741.59550426976;3864.401017842239;4333.891652708708;4344.869653910388;4350.997082684002;4373.453318335208;5473;5701;6135.447809697149;6416.262129855127;6473.2232132144445;6019.307779605227;6168.475679567287;6206.441420272197;6213.489378501133;6251.843703907439;6164;6204.317525072148;6209.782657606962;6229.536059026034;6243.438090571689;4977.328060711758;5813.505852002627;5955.374685657944;6098.969505152446;6120.143179954899;5176.559992400688;5513.649155956817;6068.302145253352;6095.573309868267;6109.969450152721;5262.815801446931;5773.719380844627;5867.386468720965;6131.382484334086;6150.969245153746 14:06:44 INFO - PROCESS | 1667 | |5;yui.html;647.6761619190405;652.5399593286734;677.1332694151484;720.9423246140309;745.253094086966;15068.171250581227;15162.09027458352;16696.582585435353;17237.482875513724;17248;15846.683066338695;15901.045937243773;15985.760213596805;16011.599710007227;16014.59963500913;725.4957804325995;748.1134855196582;938.1228551304536;946.1248345280588;946.4321407155686;910.1444642036464;947.3321308477507;947.5972711597578;947.7441090905452;948.1135138645388;983.8917719050899;991.3457118301923;992.7071513903416;994.2394068537575;994.6170724271135;753.9132999705049;955.460165006769;987.1017374189489;987.1214618989105;988.406955826502;726.3499168244444;727.5998200989435;727.7416517136446;728.10806761717;728.4645785347765;15462.149581772997;15535.223238838013;15566.29951652167;15570.532884013497;15574.14342211178;12528;12812.359382030954;12845.743085138245;12952;13184.077114601982;8558.443701159405;10139.54372053252;10261.743456413575;10385.16918646514;10896.618618348399;3478.252175782219;3479.1302174456387;3519.472079188117;3531.7174626029855;3710.9628903710886;61129;90069.74825629346;90581;91530.62704059444;92191;9488.478133702574;9509.809803803884;9575.04249575051;9795.314327997114;9809.852852207223;22153.224637137624;22190.778092219192;22211.44471388196;22223.333300001024;22244.666330005064;7310.52481588695;7336.633168341562;7427.962860185773;7503.062117235346;7782.455228133977;4011.7392369496474;4014.498187726534;4018.457508236351;4085.9591404085577;4150.709450338447;3559.430491121408;3645.635436456333;3673.9816300918324;3678.9080272992855;3685.09715119792;627.1752645271454;655.5181941273161;657.5922927784709;680.9795706128824;681.5229339462396;549.6564647095565;561.2423228641302;575.3469811763679;588.1031427073747;611.6055144431816;7325.743598974011;7899.723509677134;7968.84062318762;8147.755567332871;8565.957170214233;489.48359480748155;543.1689515041992;563.9915401268984;585.9589828712035;605.5549171359047;3678.448232765053;3908.785016824045;3936.527616686016;3984.561698213194;4003.819828107742;78720.21279787243;81538.18461815425;81703.36593268102;85180;86795;65798;68314.31685683179;70495;72018.27981720115;72434.63782681053;65544;67157.99999999903;67330.66334668295;68160.3183968164;68452.65773671199;8274.420790544604;8340.87488687658;8496.065432802263;8517;8571.100034496538;40423.1915361697;43038.35442468428;44801.77599111984;44927.550724492336;44957.605087388416;64051.67974160286;64155.67922160359;64160.67919660558;64325;64711.70576588536;10753.46232688378;11170;11180.832287515856;11229.27009744364;11662;11889.196730817814;12092.334921579397;12461.451505746314;13067.738645227233;13137.080404371985;11680.123990700562;11869.10981676395;11892.167548271538;12161;12166.773777319331;11538.154135112967;11759.941200293944;11783.882161178279;12022.218555793845;12598.937005314914;11659.475323610288;11702.941485292518;11756.801146713813;11905.82141267899;11957.701057473285;9509.619615215313;9533.856992144985;9719.076687714938;9750.171235444783;10594.884852207793;8906.643734250816;9065.546722663972;9161.954190229006;9361.672341468016;9873.456959867277 14:06:44 INFO - PROCESS | 1667 | __end_tp_report 14:06:44 INFO - PROCESS | 1667 | __start_cc_report 14:06:44 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1232 14:06:44 INFO - PROCESS | 1667 | __end_cc_report 14:06:44 INFO - PROCESS | 1667 | __startTimestamp1461272804102__endTimestamp 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | ------- Summary: start ------- 14:06:44 INFO - PROCESS | 1667 | Number of tests: 6 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5711.66 Median:5954.11 stddev:751.43 (12.6%) stddev-sans-first:730.88 14:06:44 INFO - PROCESS | 1667 | Values: 3367.2 3453.3 3509.8 3513.8 3539.6 5703.4 5755.7 5852.8 5853.2 5854.6 5737.7 5765.8 5801.5 5811.6 5891.2 4068.4 4611.0 5198.1 5198.4 5207.2 5242.5 5260.4 5275.5 5278.7 5288.4 710.6 5184.2 5298.2 5298.4 5345.1 5162.3 5198.0 5200.4 5216.6 5227.0 5140.8 5158.0 5161.6 5169.4 5213.8 5596.9 5632.9 5641.6 5644.2 5716.3 6166.6 6203.2 6250.0 6281.4 6285.6 6145.6 6181.9 6190.5 6203.6 6210.9 5645.0 5659.9 5665.1 5667.1 5684.1 6240.6 6284.8 6297.9 6300.9 6317.4 6153.8 6161.2 6176.0 6187.8 6189.2 6105.5 6234.9 6310.0 6334.5 6344.6 6137.3 6154.0 6201.3 6217.4 6222.8 6225.8 6235.8 6241.4 6262.6 6273.7 4830.5 4913.5 4921.2 4925.2 4962.7 4420.7 4428.1 4449.5 4477.5 4502.1 5611.7 5637.3 5706.9 5732.4 5735.3 6245.6 6303.1 6304.8 6323.3 6342.4 5592.5 5657.1 5672.6 5673.2 5721.2 5290.7 5358.8 5363.4 5370.7 5378.2 6209.9 6265.2 6322.5 6333.2 6343.0 5399.0 6189.7 6305.4 6322.1 6344.8 6226.1 6285.1 6313.7 6331.2 6335.7 6161.7 6234.1 6235.2 6245.6 6246.3 6233.7 6284.5 6310.8 6315.9 6316.4 5923.9 5986.6 5992.0 6049.5 6145.8 6008.3 6062.0 6068.2 6083.5 6086.6 5904.7 5928.7 5945.4 5951.8 5954.1 5899.6 6023.2 6110.2 6120.9 6143.2 5951.1 5961.3 5977.9 5981.6 5992.8 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:61167.87 Median:3363.88 stddev:133337.87 (3963.8%) stddev-sans-first:133634.06 14:06:44 INFO - PROCESS | 1667 | Values: 477.5 498.4 514.2 514.5 534.8 29087.4 36094.6 37473.3 37514.2 37535.6 38660.2 39266.4 39308.8 39469.0 39495.6 8483.8 8656.7 9351.9 9563.6 9597.3 11218.2 11557.1 11589.4 11593.7 11601.8 11185.9 11389.1 11394.9 11399.5 11427.4 8591.1 11311.6 11346.5 11481.4 11653.9 11233.4 11376.0 11392.0 11401.1 11415.5 36738.4 36887.6 37002.6 37020.0 37055.6 3265.9 3287.2 3380.1 3388.9 3587.0 27945.7 28600.0 28634.9 28658.6 29064.1 2399.9 2716.5 2726.7 2787.3 2810.6 404598.0 456183.0 460001.0 460363.0 460960.0 35041.9 58647.7 58694.0 59364.0 59442.7 35388.3 35562.6 35697.3 35732.3 35741.1 14230.0 14449.0 14476.5 14476.6 14484.9 10990.6 11208.4 11219.0 11230.5 12105.0 7455.5 7979.6 8029.2 8043.4 8078.2 3258.8 3320.1 3320.3 3325.5 3347.7 941.8 984.6 1027.2 1080.1 1093.0 23526.6 28106.0 28314.9 28673.9 29496.0 1086.3 1089.8 1115.0 1207.4 1213.3 13960.8 14656.3 14710.1 14760.5 15109.4 602429.0 606349.0 612688.9 613111.0 613865.0 53008.9 54139.7 56871.1 57804.0 57939.7 1297.8 1469.6 1470.6 1472.6 1524.2 22117.2 27320.7 27692.7 28300.3 28484.3 45247.4 45361.5 45815.8 45839.0 45916.3 410738.0 416733.0 417438.0 417450.0 418003.9 22639.7 25343.9 25715.1 25858.9 26155.0 41088.8 41102.0 41367.0 41500.8 41960.2 34387.1 34699.3 34734.5 35697.8 35798.3 29980.0 33811.6 35818.6 36333.6 36383.3 33951.6 34123.3 34515.8 35052.0 35575.5 29691.7 31002.9 31149.0 31278.2 31710.7 23624.3 23788.8 23911.9 24135.4 24216.8 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:32644.99 Median:20541.43 stddev:91616.77 (446.0%) stddev-sans-first:91841.78 14:06:44 INFO - PROCESS | 1667 | Values: 394.4 395.6 396.5 397.5 407.9 11714.2 19694.9 19766.6 20421.5 20469.6 16189.6 16571.6 18946.1 20184.7 20613.3 5541.0 6118.1 6170.3 6177.3 6179.4 5972.0 6029.0 6035.9 6039.4 6082.1 4148.8 4398.4 6233.9 6609.4 6617.4 6489.1 6574.4 6586.2 6601.3 6609.9 5555.1 5731.4 5760.5 5770.7 5773.0 21169.7 23293.8 23771.9 25857.7 26691.6 2327.6 2601.3 2890.4 2985.8 3079.2 14624.6 14815.5 14821.0 14935.4 15313.2 1682.2 1683.9 1734.3 1894.5 2023.0 90514.1 105616.5 109206.0 110374.0 112213.0 11246.8 11420.3 11528.7 11570.3 11678.5 14692.2 14804.9 14837.0 14840.6 14861.9 8235.2 8393.2 8401.6 8489.4 8508.2 8139.5 8143.2 8145.9 8257.6 8348.0 4196.1 4499.8 4539.2 4563.4 4616.7 1791.3 1797.5 1854.8 1976.3 1979.4 845.4 863.0 944.0 996.5 1025.6 16363.6 17165.1 18117.0 18408.0 19723.4 978.2 984.5 1003.9 1011.3 1034.0 10390.4 10564.0 10622.5 10639.6 10661.4 539801.0 542876.0 543842.0 557599.0 560749.0 123337.0 124322.0 125490.1 126060.0 127963.7 1019.2 1020.3 1035.5 1042.5 1044.2 13932.6 17794.7 18073.7 18195.3 18379.0 34180.7 39911.2 46373.1 46424.5 48544.8 67945.7 68993.0 69546.7 69726.3 71576.0 7750.9 7891.5 8239.4 8241.5 8403.5 9496.7 12603.7 12680.4 13030.2 13080.5 10476.0 10583.0 10771.6 11425.5 11447.3 10890.6 10989.8 11226.3 11460.3 11532.8 11338.6 11395.3 11577.2 11585.1 11702.8 8112.4 8273.7 8307.8 8470.0 8556.4 10931.8 11305.1 11346.5 11371.2 11617.1 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5729.80 Median:4935.76 stddev:3186.11 (64.6%) stddev-sans-first:3178.69 14:06:44 INFO - PROCESS | 1667 | Values: 1433.6 1607.6 1608.2 1920.1 2050.8 7946.5 8700.2 8945.6 8951.4 8953.0 8496.1 8752.1 8757.3 8758.5 8762.5 2166.3 2386.9 2464.4 2484.7 2494.3 2489.6 2519.2 2528.2 2530.6 2531.6 2428.7 2494.8 2495.6 2498.1 2500.6 2157.7 2379.7 2454.7 2462.8 2464.7 2462.9 2485.0 2511.6 2511.6 2514.3 8653.1 8720.1 8723.4 8727.3 8731.4 5326.8 5439.3 5443.5 5450.8 5475.5 3517.9 3551.1 3562.1 3593.2 3598.8 928.5 1017.5 1029.5 1041.2 1043.0 11406.3 11610.1 11631.4 11645.0 11711.8 4289.4 4692.0 4728.3 4961.6 4972.1 10110.5 10116.2 10118.0 10121.6 10140.7 5451.9 5453.5 5461.1 5463.8 5492.7 4430.6 4601.2 4821.0 4925.4 4946.2 4005.8 4019.3 4134.6 4155.0 4156.1 1356.8 1372.1 1373.8 1376.4 1384.5 902.6 917.4 939.7 940.3 968.0 5776.1 5902.8 5920.3 5963.5 5984.3 450.3 451.5 454.4 465.2 486.3 3877.2 3910.1 3957.7 3973.7 4054.4 10261.0 10625.7 10689.8 10775.9 10777.4 10602.5 10704.9 10705.3 10728.9 10766.5 7793.1 8183.2 8251.8 8255.5 8256.4 5487.6 5565.7 5646.9 5654.2 5741.6 9702.6 9722.3 9875.2 9925.0 9964.6 9840.9 10085.5 10085.9 10181.4 10207.0 7484.3 7496.3 7507.4 7569.7 7571.0 8109.6 8131.7 8225.8 8237.0 8238.0 7026.9 7316.1 7341.9 7597.5 7613.8 7319.2 7415.3 7416.1 7574.3 7581.1 7065.9 7096.0 7128.0 7144.9 7153.6 4839.9 4977.3 5040.0 5052.0 5059.6 4978.6 5035.0 5041.6 5103.6 5242.6 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5027.81 Median:5753.27 stddev:1580.47 (27.5%) stddev-sans-first:1544.38 14:06:44 INFO - PROCESS | 1667 | Values: 289.7 290.7 320.3 321.5 332.1 5798.8 5850.4 5877.9 5883.6 5930.8 5822.2 5839.9 5854.0 5855.2 5871.9 3806.7 4162.4 4247.8 4306.7 4399.4 4360.2 4404.3 4427.3 4428.3 4438.3 4399.3 4415.8 4416.6 4418.7 4423.1 3730.6 4164.7 4392.8 4406.3 4450.3 3239.3 3781.4 3809.5 3814.9 3815.6 5606.3 5606.3 5610.0 5610.6 5619.2 4254.6 4757.4 4905.9 5052.5 5145.4 5880.4 6045.6 6077.5 6094.2 6116.4 3480.8 3753.0 3789.5 4217.5 4235.9 5754.9 5787.1 5914.8 6454.4 6511.1 3870.9 4174.6 4284.4 4320.7 4340.9 4384.2 4384.6 4409.0 4414.9 4423.0 4916.3 5062.6 5074.8 5077.5 5080.0 5200.9 5557.2 6107.5 6165.7 6181.7 1238.2 1502.5 1516.8 1516.8 1521.4 854.7 950.5 975.3 989.5 1022.2 4750.6 4896.3 5006.7 5757.9 5801.6 5946.4 6455.2 6465.3 6466.7 6495.7 5523.7 5579.2 5751.6 5764.2 5766.6 2991.4 3073.9 3244.7 3286.4 3338.5 6465.4 6744.0 6750.8 6769.3 6783.6 5723.7 6567.7 6599.9 6646.0 6650.9 5902.9 6540.8 6710.3 6728.5 6743.2 6429.0 6429.6 6440.6 6452.4 6452.7 6316.3 6326.7 6349.4 6359.2 6363.4 6487.3 6669.1 6690.3 6716.4 6741.6 3864.4 4333.9 4344.9 4351.0 4373.5 5473.0 5701.0 6135.4 6416.3 6473.2 6019.3 6168.5 6206.4 6213.5 6251.8 6164.0 6204.3 6209.8 6229.5 6243.4 4977.3 5813.5 5955.4 6099.0 6120.1 5176.6 5513.6 6068.3 6095.6 6110.0 5262.8 5773.7 5867.4 6131.4 6151.0 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17776.86 Median:30878.09 stddev:24268.05 (78.6%) stddev-sans-first:24302.04 14:06:44 INFO - PROCESS | 1667 | Values: 647.7 652.5 677.1 720.9 745.3 15068.2 15162.1 16696.6 17237.5 17248.0 15846.7 15901.0 15985.8 16011.6 16014.6 725.5 748.1 938.1 946.1 946.4 910.1 947.3 947.6 947.7 948.1 983.9 991.3 992.7 994.2 994.6 753.9 955.5 987.1 987.1 988.4 726.3 727.6 727.7 728.1 728.5 15462.1 15535.2 15566.3 15570.5 15574.1 12528.0 12812.4 12845.7 12952.0 13184.1 8558.4 10139.5 10261.7 10385.2 10896.6 3478.3 3479.1 3519.5 3531.7 3711.0 61129.0 90069.7 90581.0 91530.6 92191.0 9488.5 9509.8 9575.0 9795.3 9809.9 22153.2 22190.8 22211.4 22223.3 22244.7 7310.5 7336.6 7428.0 7503.1 7782.5 4011.7 4014.5 4018.5 4086.0 4150.7 3559.4 3645.6 3674.0 3678.9 3685.1 627.2 655.5 657.6 681.0 681.5 549.7 561.2 575.3 588.1 611.6 7325.7 7899.7 7968.8 8147.8 8566.0 489.5 543.2 564.0 586.0 605.6 3678.4 3908.8 3936.5 3984.6 4003.8 78720.2 81538.2 81703.4 85180.0 86795.0 65798.0 68314.3 70495.0 72018.3 72434.6 65544.0 67158.0 67330.7 68160.3 68452.7 8274.4 8340.9 8496.1 8517.0 8571.1 40423.2 43038.4 44801.8 44927.6 44957.6 64051.7 64155.7 64160.7 64325.0 64711.7 10753.5 11170.0 11180.8 11229.3 11662.0 11889.2 12092.3 12461.5 13067.7 13137.1 11680.1 11869.1 11892.2 12161.0 12166.8 11538.2 11759.9 11783.9 12022.2 12598.9 11659.5 11702.9 11756.8 11905.8 11957.7 9509.6 9533.9 9719.1 9750.2 10594.9 8906.6 9065.5 9162.0 9361.7 9873.5 14:06:44 INFO - PROCESS | 1667 | -------- Summary: end -------- 14:06:44 INFO - PROCESS | 1667 | 14:06:44 INFO - TEST-INFO | 1667: exit 0 14:06:44 INFO - TEST-OK | dromaeo_css | took 1114466ms 14:06:44 INFO - TEST-START | kraken 14:06:44 INFO - operating with platform_type : mac_ 14:06:44 INFO - Initialising browser for kraken test... 14:06:44 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp6axY7k/profile http://localhost:49270/getInfo.html) 14:06:46 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 14:06:46 INFO - PROCESS | 1674 | colorDepth:24 14:06:46 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 14:06:46 INFO - PROCESS | 1674 | __metrics 14:06:46 INFO - PROCESS | 1674 | 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" 14:06:46 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:06:47 INFO - TEST-INFO | 1674: exit 0 14:06:47 INFO - Browser initialized. 14:06:47 INFO - Running cycle 1/1 for kraken test... 14:06:47 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp6axY7k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:06:59 INFO - PROCESS | 1676 | RSS: Main: 238985216 14:06:59 INFO - PROCESS | 1676 | 14:07:02 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 14:07:03 INFO - PROCESS | 1676 | RSS: Main: 286195712 14:07:03 INFO - PROCESS | 1676 | 14:07:05 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 14:07:06 INFO - PROCESS | 1676 | RSS: Main: 299479040 14:07:06 INFO - PROCESS | 1676 | 14:07:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 14:07:10 INFO - PROCESS | 1676 | RSS: Main: 385794048 14:07:10 INFO - PROCESS | 1676 | 14:07:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 14:07:12 INFO - PROCESS | 1676 | RSS: Main: 285200384 14:07:12 INFO - PROCESS | 1676 | 14:07:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:07:15 INFO - PROCESS | 1676 | RSS: Main: 291106816 14:07:15 INFO - PROCESS | 1676 | 14:07:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 14:07:19 INFO - PROCESS | 1676 | RSS: Main: 327569408 14:07:19 INFO - PROCESS | 1676 | 14:07:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 14:07:22 INFO - PROCESS | 1676 | RSS: Main: 328798208 14:07:22 INFO - PROCESS | 1676 | 14:07:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 14:07:25 INFO - PROCESS | 1676 | RSS: Main: 328531968 14:07:25 INFO - PROCESS | 1676 | 14:07:27 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:07:28 INFO - PROCESS | 1676 | RSS: Main: 320094208 14:07:28 INFO - PROCESS | 1676 | 14:07:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:07:30 INFO - PROCESS | 1676 | RSS: Main: 327356416 14:07:30 INFO - PROCESS | 1676 | 14:07:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:07:33 INFO - PROCESS | 1676 | RSS: Main: 330199040 14:07:33 INFO - PROCESS | 1676 | 14:07:36 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:07:37 INFO - PROCESS | 1676 | RSS: Main: 335552512 14:07:37 INFO - PROCESS | 1676 | 14:07:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:07:40 INFO - PROCESS | 1676 | RSS: Main: 332369920 14:07:40 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 14:07:42 INFO - PROCESS | 1676 | RSS: Main: 397033472 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | __start_tp_report 14:07:42 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 14:07:42 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 14:07:42 INFO - PROCESS | 1676 | |i|pagename|runs| 14:07:42 INFO - PROCESS | 1676 | |0;ai-astar;113;109;114;112;112;112;119;110;139;158 14:07:42 INFO - PROCESS | 1676 | |1;audio-beat-detection;157;123;145;167;118;139;142;119;168;118 14:07:42 INFO - PROCESS | 1676 | |2;audio-dft;186;186;187;186;185;184;184;183;184;185 14:07:42 INFO - PROCESS | 1676 | |3;audio-fft;77;73;86;90;56;59;58;79;85;56 14:07:42 INFO - PROCESS | 1676 | |4;audio-oscillator;91;97;137;104;105;106;126;78;77;127 14:07:42 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;89;86;89;86;88;85;89;86;89;86 14:07:42 INFO - PROCESS | 1676 | |6;imaging-darkroom;94;94;94;95;94;95;95;96;94;94 14:07:42 INFO - PROCESS | 1676 | |7;imaging-desaturate;86;85;84;87;83;85;83;87;85;83 14:07:42 INFO - PROCESS | 1676 | |8;json-parse-financial;59;61;59;59;58;58;61;77;61;55 14:07:42 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;64;71;70;81;98;92;89;67;66;71 14:07:42 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;80;81;83;82;80;80;96;98;98;100 14:07:42 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;140;157;149;139;138;140;137;139;184;158 14:07:42 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;169;171;169;168;207;204;176;200;206;172 14:07:42 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;69;69;70;68;72;69;68;70;69;67 14:07:42 INFO - PROCESS | 1676 | __end_tp_report 14:07:42 INFO - PROCESS | 1676 | __start_cc_report 14:07:42 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1984 14:07:42 INFO - PROCESS | 1676 | __end_cc_report 14:07:42 INFO - PROCESS | 1676 | __startTimestamp1461272862626__endTimestamp 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | ------- Summary: start ------- 14:07:42 INFO - PROCESS | 1676 | Number of tests: 14 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:119.80 Median:113.50 stddev:16.00 (14.1%) stddev-sans-first:16.78 14:07:42 INFO - PROCESS | 1676 | Values: 113.0 109.0 114.0 112.0 112.0 112.0 119.0 110.0 139.0 158.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:143.50 stddev:19.80 (13.8%) stddev-sans-first:19.97 14:07:42 INFO - PROCESS | 1676 | Values: 157.0 123.0 145.0 167.0 118.0 139.0 142.0 119.0 168.0 118.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.50 stddev:1.25 (0.7%) stddev-sans-first:1.27 14:07:42 INFO - PROCESS | 1676 | Values: 186.0 186.0 187.0 186.0 185.0 184.0 184.0 183.0 184.0 185.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:71.90 Median:78.00 stddev:13.50 (17.3%) stddev-sans-first:14.20 14:07:42 INFO - PROCESS | 1676 | Values: 77.0 73.0 86.0 90.0 56.0 59.0 58.0 79.0 85.0 56.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:107.00 stddev:20.34 (19.0%) stddev-sans-first:20.95 14:07:42 INFO - PROCESS | 1676 | Values: 91.0 97.0 137.0 104.0 105.0 106.0 126.0 78.0 77.0 127.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.50 stddev:1.64 (1.8%) stddev-sans-first:1.62 14:07:42 INFO - PROCESS | 1676 | Values: 89.0 86.0 89.0 86.0 88.0 85.0 89.0 86.0 89.0 86.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 14:07:42 INFO - PROCESS | 1676 | Values: 94.0 94.0 94.0 95.0 94.0 95.0 95.0 96.0 94.0 94.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.55 (1.8%) stddev-sans-first:1.58 14:07:42 INFO - PROCESS | 1676 | Values: 86.0 85.0 84.0 87.0 83.0 85.0 83.0 87.0 85.0 83.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:60.00 stddev:5.98 (10.0%) stddev-sans-first:6.30 14:07:42 INFO - PROCESS | 1676 | Values: 59.0 61.0 59.0 59.0 58.0 58.0 61.0 77.0 61.0 55.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:76.90 Median:76.00 stddev:12.19 (16.0%) stddev-sans-first:12.00 14:07:42 INFO - PROCESS | 1676 | Values: 64.0 71.0 70.0 81.0 98.0 92.0 89.0 67.0 66.0 71.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:82.50 stddev:8.88 (10.8%) stddev-sans-first:8.96 14:07:42 INFO - PROCESS | 1676 | Values: 80.0 81.0 83.0 82.0 80.0 80.0 96.0 98.0 98.0 100.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:144.50 stddev:14.87 (10.3%) stddev-sans-first:15.48 14:07:42 INFO - PROCESS | 1676 | Values: 140.0 157.0 149.0 139.0 138.0 140.0 137.0 139.0 184.0 158.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:188.00 stddev:17.49 (9.3%) stddev-sans-first:17.66 14:07:42 INFO - PROCESS | 1676 | Values: 169.0 171.0 169.0 168.0 207.0 204.0 176.0 200.0 206.0 172.0 14:07:42 INFO - PROCESS | 1676 | 14:07:42 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.37 (2.0%) stddev-sans-first:1.45 14:07:42 INFO - PROCESS | 1676 | Values: 69.0 69.0 70.0 68.0 72.0 69.0 68.0 70.0 69.0 67.0 14:07:42 INFO - PROCESS | 1676 | -------- Summary: end -------- 14:07:42 INFO - PROCESS | 1676 | 14:07:43 INFO - TEST-INFO | 1676: exit 0 14:07:43 INFO - TEST-OK | kraken | took 58774ms 14:07:43 INFO - SUITE-END | took 1173s 14:07:43 INFO - Completed test suite (00:19:33) 14:07:43 INFO - javascript benchmark 14:07:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.1582104473882, 3453.326601312745, 3509.8420571074307, 3513.8067406292644, 3539.557555305587, 5703.40114288, 5755.712214389274, 5852.795152169675, 5853.209816674747, 5854.560907931911, 5737.684427356494, 5765.827025189241, 5801.47786699197, 5811.564132690044, 5891.204687367215, 4068.41008053832, 4610.97694511527, 5198.064348417292, 5198.350206224222, 5207.218917162418, 5242.501962313574, 5260.447652996438, 5275.472452754713, 5278.683279003253, 5288.444713305105, 710.5919546290279, 5184.170532714747, 5298.1522956327, 5298.36419629645, 5345.091334473149, 5162.303089082963, 5198.012377648235, 5200.3759548854005, 5216.582673386158, 5227.0, 5140.820071297524, 5158.045761534128, 5161.638685292031, 5169.405518365361, 5213.791448342062, 5596.916046259309, 5632.915506267409, 5641.605087643867, 5644.153376993443, 5716.314042314907, 6166.599171053867, 6203.2245969253845, 6250.031245157008, 6281.434670879597, 6285.591436556655, 6145.600535965193, 6181.876362472771, 6190.473809726179, 6203.565750397474, 6210.937890621081, 5645.040343141717, 5659.915101273484, 5665.093585026376, 5667.1499275108235, 5684.062129748587, 6240.563160578716, 6284.842878928063, 6297.905531417033, 6300.905486417708, 6317.399847014526, 6153.815385538441, 6161.229846269216, 6175.96912015437, 6187.752489900444, 6189.195404597373, 6105.52039974972, 6234.875302493925, 6309.968450157719, 6334.461570766536, 6344.555881088372, 6137.324894261627, 6154.0, 6201.317855035943, 6217.378262173836, 6222.751089956441, 6225.844353891189, 6235.812925612239, 6241.407066328782, 6262.624242545371, 6273.7176827042895, 4830.492798256203, 4913.533214344631, 4921.163402221631, 4925.187344088257, 4962.652614317035, 4420.7126536775, 4428.114377124523, 4449.488308844524, 4477.485089214717, 4502.054568540643, 5611.719414029282, 5637.295338082739, 5706.857328566819, 5732.398098199712, 5735.311762588516, 6245.5628106033055, 6303.086052522359, 6304.810855674337, 6323.27282362525, 6342.429181373698, 5592.468715472024, 5657.12314591239, 5672.602917795714, 5673.177389278614, 5721.170430287585, 5290.7354632269, 5358.785648574091, 5363.410024897258, 5370.677759334452, 5378.2470454136455, 6209.937900620936, 6265.185525881698, 6322.462590679844, 6333.240011198685, 6343.048542718537, 5398.973005134949, 6189.659568723763, 6305.432511074026, 6322.114903913548, 6344.777932772331, 6226.066090086523, 6285.088662144055, 6313.715882785377, 6331.240251169889, 6335.65153916539, 6161.691915404302, 6234.064890266496, 6235.189425374672, 6245.6252624842655, 6246.3441338660605, 6233.719482623203, 6284.465820405317, 6310.7791227306825, 6315.90526142099, 6316.368363163647, 5923.911141332797, 5986.610870293417, 5992.0, 6049.485793707584, 6145.8156255312415, 6008.339082700987, 6062.0, 6068.150458935665, 6083.452489275809, 6086.6348019119, 5904.734286957011, 5928.733207005609, 5945.40545945402, 5951.821445356646, 5954.10688396744, 5899.557533184942, 6023.216981792339, 6110.205673262448, 6120.938790612036, 6143.201383820075, 5951.137085122719, 5961.2846458425265, 5977.850553736017, 5981.611195272293, 5992.820215393545], "value": 5666.120193257397, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.53440395614257, 498.391961806596, 514.1696160700468, 514.4726655178442, 534.8288547664749, 29087.41825163498, 36094.639053609455, 37473.250534989354, 37514.24971500568, 37535.62464375355, 38660.22679546408, 39266.41100383497, 39308.820735377914, 39469.0, 39495.60504394955, 8483.787905302355, 8656.740297791077, 9351.859722104175, 9563.617455301779, 9597.280203984694, 11218.214724969255, 11557.133215008866, 11589.36258505782, 11593.6521904343, 11601.825972610417, 11185.944070279636, 11389.088872890148, 11394.943025284862, 11399.544018239261, 11427.42862856858, 8591.140885911422, 11311.604093856758, 11346.48940797662, 11481.368524731137, 11653.941730291379, 11233.438328083563, 11376.033037191848, 11391.974722274952, 11401.087912967027, 11415.54337826486, 36738.44892326617, 36887.63112368869, 37002.62997370046, 37020.0, 37055.62944370549, 3265.9346813063607, 3287.211069343364, 3380.1042723678247, 3388.8644454221803, 3586.9700670438247, 27945.720542794515, 28600.0, 28634.85682571574, 28658.570121448196, 29064.1108327958, 2399.880005999693, 2716.4702882937836, 2726.740959608834, 2787.3310405502734, 2810.6065150878894, 404597.977010116, 456183.0, 460001.0, 460363.0, 460960.0, 35041.9189217562, 58647.70676146592, 58694.0, 59364.0, 59442.702786486654, 35388.292234155175, 35562.64437355593, 35697.28605427929, 35732.285354293286, 35741.10647233788, 14230.003899727128, 14448.988570799944, 14476.493322733653, 14476.638084047772, 14484.927575362055, 10990.615328463466, 11208.383538905275, 11219.0, 11230.494627741771, 12105.0, 7455.478116531899, 7979.601019948979, 8029.156938521488, 8043.356531477468, 8078.23256790604, 3258.8207648579555, 3320.1201681554408, 3320.335932813399, 3325.48454989477, 3347.6652334766327, 941.8398872191743, 984.5913945712449, 1027.2141811514198, 1080.1466841195368, 1092.9508172132114, 23526.64710029351, 28106.016289429772, 28314.85842570774, 28673.856630716713, 29496.0, 1086.2884810449168, 1089.7657003744232, 1114.9888501114885, 1207.3661327802974, 1213.3266037349179, 13960.790588141186, 14656.267186640625, 14710.117392956457, 14760.48338308154, 15109.39562417491, 602429.0, 606349.0, 612688.9365553143, 613111.0, 613865.0, 53008.93982120336, 54139.729301354026, 56871.146932796044, 57804.0, 57939.71030144822, 1297.7664020476216, 1469.5591322603177, 1470.624990627383, 1472.63184203949, 1524.1540944775534, 22117.166671622323, 27320.726792731817, 27692.723072769015, 28300.292492687848, 28484.287892802844, 45247.41634042924, 45361.54638453573, 45815.77092114518, 45839.0, 45916.311255331864, 410738.0, 416733.0, 417438.0, 417450.0, 418003.9099804603, 22639.660405093608, 25343.873280633477, 25715.09997150091, 25858.870705647103, 26155.0, 41088.794556028224, 41102.0, 41367.0, 41500.79249603733, 41960.160796784505, 34387.14032149216, 34699.306013879075, 34734.47898281578, 35697.82151089228, 35798.28403431969, 29980.0, 33811.64753409836, 35818.64181358153, 36333.63666363303, 36383.27233455369, 33951.641934323336, 34123.31753364869, 34515.82742086273, 35052.0, 35575.46636800398, 29691.703082969758, 31002.914897978462, 31149.0, 31278.218044548157, 31710.68289317077, 23624.29127126189, 23788.762112379347, 23911.92396342134, 24135.396615084763, 24216.757832421452], "value": 17964.30130288617, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.4359565820876, 395.57870867526094, 396.54001358424233, 397.4515169066689, 407.86132714876953, 11714.177192999532, 19694.916779577117, 19766.60466790665, 20421.48946276344, 20469.590608187827, 16189.60384703455, 16571.58571035725, 18946.147423365946, 20184.69722954157, 20613.27853525127, 5541.0, 6118.11287363332, 6170.259568851744, 6177.289611694645, 6179.412955769195, 5972.0444728843395, 6029.035354343306, 6035.939640603582, 6039.426254505837, 6082.057281121433, 4148.8340466381305, 4398.362237475564, 6233.906491402633, 6609.4051535361805, 6617.437517810992, 6489.091527186198, 6574.441172500343, 6586.176727909012, 6601.306862779387, 6609.867802643968, 5555.138953462219, 5731.369549349568, 5760.452756988079, 5770.653760774325, 5773.0, 21169.68245476319, 23293.83530823452, 23771.88114059436, 25857.707114644192, 26691.599626005627, 2327.6043072677685, 2601.2846467221475, 2890.3930174663346, 2985.805922615023, 3079.2148002259505, 14624.634384140376, 14815.48145814902, 14820.962532622721, 14935.402583896632, 15313.23433828304, 1682.1757338903972, 1683.9326426942907, 1734.3062774890018, 1894.526368407898, 2023.0390564481754, 90514.09485905123, 105616.4719176407, 109206.0, 110374.0, 112213.0, 11246.831297530543, 11420.344050112853, 11528.711782205344, 11570.305781653127, 11678.532858685558, 14692.19192944377, 14804.925975370053, 14837.0, 14840.62898427548, 14861.851381486264, 8235.21765432283, 8393.202645748644, 8401.579921003926, 8489.405741598152, 8508.234258916604, 8139.4709343892455, 8143.22697978106, 8145.877811832827, 8257.587120643944, 8348.039975402786, 4196.118815048813, 4499.752513611717, 4539.205639013159, 4563.429571303587, 4616.699914505546, 1791.2834866053422, 1797.451777207938, 1854.7866995295444, 1976.2589029114083, 1979.3962841333243, 845.4166625028711, 862.9870551941726, 944.0417975754615, 996.4718699089494, 1025.6102680981328, 16363.60909322697, 17165.055921924166, 18117.0, 18408.0, 19723.408297750804, 978.1538968791943, 984.4831463481586, 1003.8996100390049, 1011.3375738891039, 1033.9689809305733, 10390.376577405228, 10564.0, 10622.521986510626, 10639.627613033506, 10661.413622250695, 539801.0, 542876.0, 543842.0, 557599.0, 560749.0, 123337.0, 124322.0, 125490.11764823535, 126060.0, 127963.72036279704, 1019.1591936652144, 1020.3316827478013, 1035.471909326249, 1042.4839704346398, 1044.2116202267239, 13932.58202253934, 17794.669978602145, 18073.728894066335, 18195.272189112817, 18379.0, 34180.658193417745, 39911.2017759649, 46373.07253854971, 46424.53575464337, 48544.786380340774, 67945.66027169832, 68993.0, 69546.65226674036, 69726.25684357932, 71576.0, 7750.914871917823, 7891.487053341514, 8239.423240373117, 8241.50550966944, 8403.495790252604, 9496.667616633385, 12603.747925041265, 12680.429380677708, 13030.218186908816, 13080.542181023618, 10476.0, 10582.994615511514, 10771.569137234423, 11425.542978281108, 11447.313161210353, 10890.61882834097, 10989.835152472559, 11226.270292430965, 11460.255083419552, 11532.827007594726, 11338.60314888975, 11395.316281023164, 11577.247478913843, 11585.116260495339, 11702.765944681229, 8112.43212975086, 8273.669053238043, 8307.750767476986, 8469.983601967804, 8556.358273129415, 10931.781364372828, 11305.095592352756, 11346.489407976826, 11371.20401571882, 11617.0706343494], "value": 9519.742161115977, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.605758416436, 1607.6463178100819, 1608.163754847479, 1920.087958219845, 2050.84618653601, 7946.4834785738885, 8700.216980471756, 8945.64217431302, 8951.418157819737, 8953.015168331478, 8496.065432802403, 8752.124787521261, 8757.343199260065, 8758.474491530513, 8762.474251544896, 2166.2959538150085, 2386.9283921482383, 2464.408541949928, 2484.7391023942405, 2494.2641920633364, 2489.55188066148, 2519.2064499682633, 2528.2415275417393, 2530.6457096006484, 2531.582288922335, 2428.7206971198275, 2494.775470207681, 2495.6007038873786, 2498.088197807805, 2500.599904015358, 2157.719496465465, 2379.6787433696577, 2454.6808914841044, 2462.827602067856, 2464.691913510811, 2462.9137980170785, 2484.962725559118, 2511.573032584465, 2511.5981442969037, 2514.2582938033324, 8653.134686531359, 8720.127987201293, 8723.38936274461, 8727.34544909128, 8731.388802783807, 5326.813561525367, 5439.292891924064, 5443.482869127427, 5450.809221677222, 5475.521609165543, 3517.859285628572, 3551.094470909927, 3562.09166662502, 3593.2094939113354, 3598.7660802047785, 928.535732133933, 1017.4963393120379, 1029.4646783672524, 1041.1930753665895, 1042.9634962776267, 11406.258593191416, 11610.129240307011, 11631.418429078512, 11645.0, 11711.765764684658, 4289.399484072233, 4691.976540117277, 4728.338032675429, 4961.627877909171, 4972.105021096238, 10110.494475276208, 10116.241281903887, 10118.0, 10121.595136194617, 10140.746481338025, 5451.863703407367, 5453.536449401766, 5461.1262198048125, 5463.754131064112, 5492.725363731877, 4430.62339701129, 4601.217792975202, 4820.9517904820505, 4925.359703238556, 4946.159152944008, 4005.7997100144876, 4019.31671615826, 4134.586541345901, 4154.958450415457, 4156.06488540076, 1356.8236129303127, 1372.1286982765816, 1373.807666926631, 1376.3875075591466, 1384.508499482669, 902.6479672927563, 917.3578495053489, 939.7016472182688, 940.3135710931059, 968.0, 5776.104703770922, 5902.793402230901, 5920.259967504062, 5963.463288304073, 5984.3417224105315, 450.28629622048766, 451.52589780730756, 454.3820404250217, 465.1650287733526, 486.2924444932614, 3877.2245550889934, 3910.081130934228, 3957.683385329167, 3973.662238709684, 4054.351303791438, 10261.0, 10625.734356641145, 10689.83965240522, 10775.892241077645, 10777.353358798497, 10602.522886470128, 10704.946475267574, 10705.30415523004, 10728.946355268174, 10766.461676916122, 7793.12964888434, 8183.222593853575, 8251.844741736162, 8255.463394879194, 8256.380771442165, 5487.6158668892795, 5565.7217139143695, 5646.858828529155, 5654.208410822571, 5741.598088133791, 9702.6118955241, 9722.27082968766, 9875.160611348116, 9924.950375248078, 9964.55159517838, 9840.852387214054, 10085.546150423103, 10085.949570252395, 10181.440020798927, 10206.979302069734, 7484.25157484269, 7496.325330720261, 7507.399408047234, 7569.697212111454, 7570.977917981002, 8109.635066421907, 8131.715389961323, 8225.83548329042, 8237.0, 8237.958810205912, 7026.8945965809535, 7316.122065352191, 7341.853162936818, 7597.506162099446, 7613.809654758676, 7319.158296795937, 7415.295546923034, 7416.0729908761405, 7574.280443357872, 7581.128170260491, 7065.929340706733, 7096.0, 7128.0, 7144.85710285781, 7153.60655163971, 4839.903201935871, 4977.328060711758, 5039.974800125976, 5051.989602079672, 5059.5952323814745, 4978.576820970114, 5034.974825125851, 5041.6470847040355, 5103.5661968733075, 5242.633015688865], "value": 4556.766086667369, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.6755633690267, 290.7412402961366, 320.2538086259017, 321.4567381125896, 332.1347888749808, 5798.768049278029, 5850.3564607893095, 5877.853053673661, 5883.646981181121, 5930.822075337746, 5822.214001109859, 5839.9124013139835, 5853.95111710901, 5855.160247280076, 5871.911921321183, 3806.676432503227, 4162.354835000579, 4247.8088486018205, 4306.74159550428, 4399.350243658628, 4360.193364227613, 4404.317330813729, 4427.325319984421, 4428.335749637548, 4438.313440892456, 4399.252127138394, 4415.757133357664, 4416.646668266547, 4418.734875907456, 4423.137488189805, 3730.645588669072, 4164.666826653875, 4392.824287028547, 4406.272964960777, 4450.332450132473, 3239.2549713566013, 3781.4327850822324, 3809.5047643806406, 3814.923701525954, 3815.585051379028, 5606.271184745957, 5606.271184745957, 5610.0, 5610.625396777816, 5619.213310136584, 4254.595983325496, 4757.381540399726, 4905.8773530661665, 5052.545270925634, 5145.434002259748, 5880.382559831199, 6045.637261764265, 6077.483413909823, 6094.238220222473, 6116.3577824328695, 3480.8085555294447, 3753.0, 3789.5073640426845, 4217.472815898012, 4235.9364609530885, 5754.913676294859, 5787.13193021046, 5914.846604912044, 6454.419102280817, 6511.0558968949235, 3870.8838734838, 4174.5616710244985, 4284.421603083606, 4320.675949303814, 4340.934885976713, 4384.232759267115, 4384.605385515319, 4408.977955110204, 4414.911701765946, 4422.977885110554, 4916.287138364989, 5062.594992400599, 5074.771635276422, 5077.517635824591, 5079.974600126976, 5200.869978250574, 5557.166425036196, 6107.511399088063, 6165.722542485599, 6181.690915454209, 1238.158052524292, 1502.481643832876, 1516.7649014402784, 1516.8168828313933, 1521.361028368088, 854.7393045121298, 950.4914870544226, 975.2685485885586, 989.5250279865704, 1022.2282176956351, 4750.61995040396, 4896.338994235733, 5006.749662516932, 5757.877213943241, 5801.593888427854, 5946.375630558729, 6455.193100862392, 6465.321141280192, 6466.708998095097, 6495.745286837129, 5523.6685798852195, 5579.19101730255, 5751.626144300607, 5764.221830052889, 5766.567507436959, 2991.4316279906748, 3073.863664752975, 3244.740420766333, 3286.4413049781597, 3338.4825352070466, 6465.418112369815, 6743.966280168666, 6750.797476075725, 6769.32306769329, 6783.590666067096, 5723.6851973141875, 6567.719294737481, 6599.868002639921, 6645.966770166118, 6650.938209155177, 5902.852428689232, 6540.771073012422, 6710.261871194165, 6728.510451133112, 6743.157105361928, 6428.967855160694, 6429.582077165156, 6440.645764482999, 6452.419282264431, 6452.677366131581, 6316.273628532777, 6326.746930122928, 6349.428551430394, 6359.236891573041, 6363.372877459803, 6487.286398496256, 6669.1330127083165, 6690.29751876046, 6716.3955244028275, 6741.59550426976, 3864.401017842239, 4333.891652708708, 4344.869653910388, 4350.997082684002, 4373.453318335208, 5473.0, 5701.0, 6135.447809697149, 6416.262129855127, 6473.2232132144445, 6019.307779605227, 6168.475679567287, 6206.441420272197, 6213.489378501133, 6251.843703907439, 6164.0, 6204.317525072148, 6209.782657606962, 6229.536059026034, 6243.438090571689, 4977.328060711758, 5813.505852002627, 5955.374685657944, 6098.969505152446, 6120.143179954899, 5176.559992400688, 5513.649155956817, 6068.302145253352, 6095.573309868267, 6109.969450152721, 5262.815801446931, 5773.719380844627, 5867.386468720965, 6131.382484334086, 6150.969245153746], "value": 4511.2934187986375, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.6761619190405, 652.5399593286734, 677.1332694151484, 720.9423246140309, 745.253094086966, 15068.171250581227, 15162.09027458352, 16696.582585435353, 17237.482875513724, 17248.0, 15846.683066338695, 15901.045937243773, 15985.760213596805, 16011.599710007227, 16014.59963500913, 725.4957804325995, 748.1134855196582, 938.1228551304536, 946.1248345280588, 946.4321407155686, 910.1444642036464, 947.3321308477507, 947.5972711597578, 947.7441090905452, 948.1135138645388, 983.8917719050899, 991.3457118301923, 992.7071513903416, 994.2394068537575, 994.6170724271135, 753.9132999705049, 955.460165006769, 987.1017374189489, 987.1214618989105, 988.406955826502, 726.3499168244444, 727.5998200989435, 727.7416517136446, 728.10806761717, 728.4645785347765, 15462.149581772997, 15535.223238838013, 15566.29951652167, 15570.532884013497, 15574.14342211178, 12528.0, 12812.359382030954, 12845.743085138245, 12952.0, 13184.077114601982, 8558.443701159405, 10139.54372053252, 10261.743456413575, 10385.16918646514, 10896.618618348399, 3478.252175782219, 3479.1302174456387, 3519.472079188117, 3531.7174626029855, 3710.9628903710886, 61129.0, 90069.74825629346, 90581.0, 91530.62704059444, 92191.0, 9488.478133702574, 9509.809803803884, 9575.04249575051, 9795.314327997114, 9809.852852207223, 22153.224637137624, 22190.778092219192, 22211.44471388196, 22223.333300001024, 22244.666330005064, 7310.52481588695, 7336.633168341562, 7427.962860185773, 7503.062117235346, 7782.455228133977, 4011.7392369496474, 4014.498187726534, 4018.457508236351, 4085.9591404085577, 4150.709450338447, 3559.430491121408, 3645.635436456333, 3673.9816300918324, 3678.9080272992855, 3685.09715119792, 627.1752645271454, 655.5181941273161, 657.5922927784709, 680.9795706128824, 681.5229339462396, 549.6564647095565, 561.2423228641302, 575.3469811763679, 588.1031427073747, 611.6055144431816, 7325.743598974011, 7899.723509677134, 7968.84062318762, 8147.755567332871, 8565.957170214233, 489.48359480748155, 543.1689515041992, 563.9915401268984, 585.9589828712035, 605.5549171359047, 3678.448232765053, 3908.785016824045, 3936.527616686016, 3984.561698213194, 4003.819828107742, 78720.21279787243, 81538.18461815425, 81703.36593268102, 85180.0, 86795.0, 65798.0, 68314.31685683179, 70495.0, 72018.27981720115, 72434.63782681053, 65544.0, 67157.99999999903, 67330.66334668295, 68160.3183968164, 68452.65773671199, 8274.420790544604, 8340.87488687658, 8496.065432802263, 8517.0, 8571.100034496538, 40423.1915361697, 43038.35442468428, 44801.77599111984, 44927.550724492336, 44957.605087388416, 64051.67974160286, 64155.67922160359, 64160.67919660558, 64325.0, 64711.70576588536, 10753.46232688378, 11170.0, 11180.832287515856, 11229.27009744364, 11662.0, 11889.196730817814, 12092.334921579397, 12461.451505746314, 13067.738645227233, 13137.080404371985, 11680.123990700562, 11869.10981676395, 11892.167548271538, 12161.0, 12166.773777319331, 11538.154135112967, 11759.941200293944, 11783.882161178279, 12022.218555793845, 12598.937005314914, 11659.475323610288, 11702.941485292518, 11756.801146713813, 11905.82141267899, 11957.701057473285, 9509.619615215313, 9533.856992144985, 9719.076687714938, 9750.171235444783, 10594.884852207793, 8906.643734250816, 9065.546722663972, 9161.954190229006, 9361.672341468016, 9873.456959867277], "value": 6930.782285147421, "unit": "score"}], "name": "dromaeo_css", "value": 7189.244666729717, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 114.0, 112.0, 112.0, 112.0, 119.0, 110.0, 139.0, 158.0], "value": 119.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 145.0, 167.0, 118.0, 139.0, 142.0, 119.0, 168.0, 118.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 187.0, 186.0, 185.0, 184.0, 184.0, 183.0, 184.0, 185.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 86.0, 90.0, 56.0, 59.0, 58.0, 79.0, 85.0, 56.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 97.0, 137.0, 104.0, 105.0, 106.0, 126.0, 78.0, 77.0, 127.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 88.0, 85.0, 89.0, 86.0, 89.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 96.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 87.0, 83.0, 85.0, 83.0, 87.0, 85.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 59.0, 58.0, 58.0, 61.0, 77.0, 61.0, 55.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 70.0, 81.0, 98.0, 92.0, 89.0, 67.0, 66.0, 71.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 83.0, 82.0, 80.0, 80.0, 96.0, 98.0, 98.0, 100.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 157.0, 149.0, 139.0, 138.0, 140.0, 137.0, 139.0, 184.0, 158.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 169.0, 168.0, 207.0, 204.0, 176.0, 200.0, 206.0, 172.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 68.0, 72.0, 69.0, 68.0, 70.0, 69.0, 67.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1514.5999999999997, "alertThreshold": 2.0}]} 14:07:43 INFO - Return code: 0 14:07:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:07:43 ERROR - # TBPL SUCCESS # 14:07:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:07:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:07:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:07:43 INFO - 2016-04-21 14:07:43.941 screenresolution[1680:9711] starting screenresolution argv=screenresolution get 14:07:43 INFO - 2016-04-21 14:07:43.944 screenresolution[1680:9711] Display 0: 1600x1200x32@50 14:07:43 INFO - 2016-04-21 14:07:43.950 screenresolution[1681:9714] starting screenresolution argv=screenresolution list 14:07:43 INFO - Available Modes on Display 0 14:07:43 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 14:07:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:07:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:07:43 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 14:07:43 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 14:07:44 INFO - Graphics/Displays: 14:07:44 INFO - Intel Iris: 14:07:44 INFO - Chipset Model: Intel Iris 14:07:44 INFO - Type: GPU 14:07:44 INFO - Bus: Built-In 14:07:44 INFO - VRAM (Dynamic, Max): 1536 MB 14:07:44 INFO - Vendor: Intel (0x8086) 14:07:44 INFO - Device ID: 0x0a2e 14:07:44 INFO - Revision ID: 0x0009 14:07:44 INFO - Displays: 14:07:44 INFO - Display: 14:07:44 INFO - Resolution: 1600 x 1200 @ 50 Hz 14:07:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:07:44 INFO - Display Serial Number: 1600x1200 50 14:07:44 INFO - Main Display: Yes 14:07:44 INFO - Mirror: Off 14:07:44 INFO - Online: Yes 14:07:44 INFO - Rotation: Supported 14:07:44 INFO - Return code: 0 14:07:44 INFO - Running post-action listener: _resource_record_post_action 14:07:44 INFO - Running post-run listener: _resource_record_post_run 14:07:45 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37828096; Write bytes: 692424704; Read time: 402; Write time: 1954 14:07:45 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389380608; Write bytes: 376423936; Read time: 29553; Write time: 696 14:07:45 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30768640; Write bytes: 294124032; Read time: 313; Write time: 1213 14:07:45 INFO - Running post-run listener: _upload_blobber_files 14:07:45 INFO - Blob upload gear active. 14:07:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:07:45 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 14:07:45 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'] 14:07:45 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 14:07:45 INFO - (blobuploader) - INFO - Open directory for files ... 14:07:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:07:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:07:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:46 INFO - (blobuploader) - INFO - Done attempting. 14:07:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:07:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:07:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:47 INFO - (blobuploader) - INFO - Done attempting. 14:07:47 INFO - (blobuploader) - INFO - Iteration through files over. 14:07:47 INFO - Return code: 0 14:07:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:07:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:07:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e98b509cf623f16890aa7111c1ca2f176bbad062a6e9c0df717d2b421a1c46007cbab1cb9faae1d6cef0e584d145b93368ece2ed7027d7fcef7e721bf180e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c13bb29812f616c0e3c03f909bd4ea69eb1f8e6e821af571762d5c100f52e2709bf0c8dba195716819121bc674d98aee732e52de1ca8c043653270bb0690b00"} 14:07:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:07:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:07:47 INFO - Contents: 14:07:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e98b509cf623f16890aa7111c1ca2f176bbad062a6e9c0df717d2b421a1c46007cbab1cb9faae1d6cef0e584d145b93368ece2ed7027d7fcef7e721bf180e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c13bb29812f616c0e3c03f909bd4ea69eb1f8e6e821af571762d5c100f52e2709bf0c8dba195716819121bc674d98aee732e52de1ca8c043653270bb0690b00"} 14:07:47 INFO - Running post-run listener: copy_logs_to_upload_dir 14:07:47 INFO - Copying logs to upload dir... 14:07:47 INFO - mkdir: /builds/slave/test/build/upload/logs 14:07:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.075430 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-21 14:07:47.916743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 14:07:47.920124) ========= 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.imH7ZjHkbj/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.cybx5DeF12/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5e98b509cf623f16890aa7111c1ca2f176bbad062a6e9c0df717d2b421a1c46007cbab1cb9faae1d6cef0e584d145b93368ece2ed7027d7fcef7e721bf180e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c13bb29812f616c0e3c03f909bd4ea69eb1f8e6e821af571762d5c100f52e2709bf0c8dba195716819121bc674d98aee732e52de1ca8c043653270bb0690b00"} build_url:https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010971 build_url: 'https://queue.taskcluster.net/v1/task/N50WVc2kS6eNX4SaxAll3Q/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/5e98b509cf623f16890aa7111c1ca2f176bbad062a6e9c0df717d2b421a1c46007cbab1cb9faae1d6cef0e584d145b93368ece2ed7027d7fcef7e721bf180e64", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c13bb29812f616c0e3c03f909bd4ea69eb1f8e6e821af571762d5c100f52e2709bf0c8dba195716819121bc674d98aee732e52de1ca8c043653270bb0690b00"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 14:07:47.947865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:07:47.948201) ========= 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.imH7ZjHkbj/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.cybx5DeF12/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005120 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 14:07:47.996871) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 14:07:47.997188) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 14:07:47.997580) ========= ========= Total master_lag: 0.57 =========