builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0032 starttime: 1462583059.39 results: success (0) buildid: 20160506161844 builduid: af4cb0eabbd54d7d9439215173f25315 revision: ddb972fb7c3721d82fb05774bbaf41806f13e545 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.392057) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.392473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.392744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.413911) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.414210) ========= 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.b2amUv0IRK/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.7H8ErLcac7/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-06 18:04:19-- 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% 9.75M=0.001s 2016-05-06 18:04:19 (9.75 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.510116 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.941360) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.941660) ========= 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.b2amUv0IRK/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.7H8ErLcac7/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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.027526 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:19.989818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 18:04:19.990168) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ddb972fb7c3721d82fb05774bbaf41806f13e545 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ddb972fb7c3721d82fb05774bbaf41806f13e545 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b2amUv0IRK/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.7H8ErLcac7/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-06 18:04:20,068 truncating revision to first 12 chars 2016-05-06 18:04:20,068 Setting DEBUG logging. 2016-05-06 18:04:20,068 attempt 1/10 2016-05-06 18:04:20,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ddb972fb7c37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 18:04:21,207 unpacking tar archive at: mozilla-aurora-ddb972fb7c37/testing/mozharness/ program finished with exit code 0 elapsedTime=1.387672 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 18:04:21.393382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.393680) ========= script_repo_revision: ddb972fb7c3721d82fb05774bbaf41806f13e545 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.394088) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.394359) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:04:21.408112) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-06 18:04:21.408482) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b2amUv0IRK/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.7H8ErLcac7/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:04:21 INFO - MultiFileLogger online at 20160506 18:04:21 in /builds/slave/test 18:04:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:04:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:21 INFO - {'append_to_log': False, 18:04:21 INFO - 'base_work_dir': '/builds/slave/test', 18:04:21 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:04:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:21 INFO - 'branch': 'Mozilla-Aurora', 18:04:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:21 INFO - 'config_files': ('talos/mac_config.py',), 18:04:21 INFO - 'default_actions': ('clobber', 18:04:21 INFO - 'read-buildbot-config', 18:04:21 INFO - 'download-and-extract', 18:04:21 INFO - 'populate-webroot', 18:04:21 INFO - 'create-virtualenv', 18:04:21 INFO - 'install', 18:04:21 INFO - 'run-tests'), 18:04:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:21 INFO - 'download_minidump_stackwalk': True, 18:04:21 INFO - 'download_symbols': 'ondemand', 18:04:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:21 INFO - 'installer_path': 'installer.exe', 18:04:21 INFO - 'log_level': 'info', 18:04:21 INFO - 'log_name': 'talos', 18:04:21 INFO - 'log_to_console': True, 18:04:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:04:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:04:21 INFO - 'opt_config_files': (), 18:04:21 INFO - 'pip_index': False, 18:04:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:21 INFO - 'cmd': ('bash', 18:04:21 INFO - '-c', 18:04:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:21 INFO - 'enabled': True, 18:04:21 INFO - 'halt_on_failure': False, 18:04:21 INFO - 'name': 'check_screen_resolution'},), 18:04:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:21 INFO - 'cmd': ('bash', 18:04:21 INFO - '-c', 18:04:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:21 INFO - 'enabled': True, 18:04:21 INFO - 'halt_on_failure': False, 18:04:21 INFO - 'name': 'check_screen_resolution'},), 18:04:21 INFO - 'run_cmd_checks_enabled': True, 18:04:21 INFO - 'sps_profile': False, 18:04:21 INFO - 'sps_profile_interval': 0, 18:04:21 INFO - 'suite': 'dromaeojs', 18:04:21 INFO - 'system_bits': '32', 18:04:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:21 INFO - 'title': 't-yosemite-r7-0032', 18:04:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:21 INFO - 'use_talos_json': True, 18:04:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:21 INFO - 'work_dir': 'build'} 18:04:21 INFO - ##### 18:04:21 INFO - ##### Running clobber step. 18:04:21 INFO - ##### 18:04:21 INFO - Running pre-action listener: _resource_record_pre_action 18:04:21 INFO - Running main action method: clobber 18:04:21 INFO - rmtree: /builds/slave/test/build 18:04:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:23 INFO - Running post-action listener: _resource_record_post_action 18:04:23 INFO - ##### 18:04:23 INFO - ##### Running read-buildbot-config step. 18:04:23 INFO - ##### 18:04:23 INFO - Running pre-action listener: _resource_record_pre_action 18:04:23 INFO - Running main action method: read_buildbot_config 18:04:23 INFO - Using buildbot properties: 18:04:23 INFO - { 18:04:23 INFO - "project": "", 18:04:23 INFO - "product": "firefox", 18:04:23 INFO - "script_repo_revision": "production", 18:04:23 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:04:23 INFO - "repository": "", 18:04:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:04:23 INFO - "buildid": "20160506161844", 18:04:23 INFO - "pgo_build": "False", 18:04:23 INFO - "basedir": "/builds/slave/test", 18:04:23 INFO - "buildnumber": 3, 18:04:23 INFO - "slavename": "t-yosemite-r7-0032", 18:04:23 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:04:23 INFO - "platform": "yosemite_r7", 18:04:23 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:04:23 INFO - "branch": "mozilla-aurora", 18:04:23 INFO - "repo_path": "releases/mozilla-aurora", 18:04:23 INFO - "revision": "ddb972fb7c3721d82fb05774bbaf41806f13e545", 18:04:23 INFO - "stage_platform": "macosx64", 18:04:23 INFO - "builduid": "af4cb0eabbd54d7d9439215173f25315", 18:04:23 INFO - "slavebuilddir": "test" 18:04:23 INFO - } 18:04:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:04:23 INFO - Running post-action listener: _resource_record_post_action 18:04:23 INFO - ##### 18:04:23 INFO - ##### Running download-and-extract step. 18:04:23 INFO - ##### 18:04:23 INFO - Running pre-action listener: _resource_record_pre_action 18:04:23 INFO - Running main action method: download_and_extract 18:04:23 INFO - mkdir: /builds/slave/test/build/tests 18:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:23 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:04:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:04:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:04:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:04:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 18:04:25 INFO - Downloaded 1199 bytes. 18:04:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:04:25 INFO - Using the following test package requirements: 18:04:25 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:04:25 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:04:25 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:04:25 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'jsshell-mac.zip'], 18:04:25 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:04:25 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:04:25 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:04:25 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:04:25 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:04:25 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:04:25 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:04:25 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:04: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')]} 18:04:25 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:04:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:04:26 INFO - Downloaded 18088827 bytes. 18:04:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:04:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:04:29 INFO - Return code: 0 18:04:29 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:04: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')]} 18:04:29 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:04:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:04:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:04:30 INFO - Downloaded 18088827 bytes. 18:04:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:04:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:04:34 INFO - Return code: 0 18:04:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:34 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:04:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:04:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:04:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:04:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:04:35 INFO - Downloaded 10840145 bytes. 18:04:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:04:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:04:35 INFO - Return code: 0 18:04:35 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')]} 18:04:35 INFO - https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:04:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 18:04:40 INFO - Downloaded 114088008 bytes. 18:04:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:40 INFO - mkdir: /builds/slave/test/properties 18:04:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:40 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:40 INFO - Contents: 18:04:40 INFO - build_url:https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:04:41 INFO - Running post-action listener: _resource_record_post_action 18:04:41 INFO - Running post-action listener: set_extra_try_arguments 18:04:41 INFO - ##### 18:04:41 INFO - ##### Running populate-webroot step. 18:04:41 INFO - ##### 18:04:41 INFO - Running pre-action listener: _resource_record_pre_action 18:04:41 INFO - Running main action method: populate_webroot 18:04:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:04:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:04:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:04:41 INFO - '--tppagecycles', 18:04:41 INFO - '7'], 18:04:41 INFO - 'tests': ['tsvgm']}}, 18:04:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:04:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:04:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:04:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:04:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:04:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['damp', 'tps']}, 18:04:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'tests': ['damp', 'tps']}, 18:04:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['dromaeo_dom']}, 18:04:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:04:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['a11yr', 18:04:41 INFO - 'ts_paint', 18:04:41 INFO - 'tpaint', 18:04:41 INFO - 'sessionrestore', 18:04:41 INFO - 'sessionrestore_no_auto_restore', 18:04:41 INFO - 'tabpaint']}, 18:04:41 INFO - 'other-e10s': {'tests': ['a11yr', 18:04:41 INFO - 'ts_paint', 18:04:41 INFO - 'tpaint', 18:04:41 INFO - 'sessionrestore', 18:04:41 INFO - 'sessionrestore_no_auto_restore', 18:04:41 INFO - 'tabpaint']}, 18:04:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:04:41 INFO - 'ts_paint', 18:04:41 INFO - 'tpaint', 18:04:41 INFO - 'sessionrestore', 18:04:41 INFO - 'sessionrestore_no_auto_restore', 18:04:41 INFO - 'tabpaint']}, 18:04:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:04:41 INFO - 'ts_paint', 18:04:41 INFO - 'tpaint', 18:04:41 INFO - 'sessionrestore', 18:04:41 INFO - 'sessionrestore_no_auto_restore', 18:04:41 INFO - 'tabpaint']}, 18:04:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['a11yr', 18:04:41 INFO - 'ts_paint', 18:04:41 INFO - 'tpaint', 18:04:41 INFO - 'sessionrestore', 18:04:41 INFO - 'sessionrestore_no_auto_restore', 18:04:41 INFO - 'tabpaint']}, 18:04:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['a11yr', 18:04:41 INFO - 'ts_paint', 18:04:41 INFO - 'tpaint', 18:04:41 INFO - 'sessionrestore', 18:04:41 INFO - 'sessionrestore_no_auto_restore', 18:04:41 INFO - 'tabpaint']}, 18:04:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['tsvgx', 18:04:41 INFO - 'tsvgr_opacity', 18:04:41 INFO - 'tart', 18:04:41 INFO - 'tscrollx', 18:04:41 INFO - 'cart']}, 18:04:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:04:41 INFO - 'tsvgr_opacity', 18:04:41 INFO - 'tart', 18:04:41 INFO - 'tscrollx', 18:04:41 INFO - 'cart']}, 18:04:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'talos_options': ['--disable-e10s'], 18:04:41 INFO - 'tests': ['tp5o']}, 18:04:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'tests': ['tp5o']}, 18:04:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'talos_options': ['--disable-e10s', 18:04:41 INFO - '--xperf_path', 18:04:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:41 INFO - 'tests': ['tp5n']}, 18:04:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:04:41 INFO - 'talos_options': ['--xperf_path', 18:04:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:04:41 INFO - 'tests': ['tp5n']}}, 18:04:41 INFO - 'talos.zip': {'path': '', 18:04:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:04:41 INFO - Running post-action listener: _resource_record_post_action 18:04:41 INFO - ##### 18:04:41 INFO - ##### Running create-virtualenv step. 18:04:41 INFO - ##### 18:04:41 INFO - Running pre-action listener: _resource_record_pre_action 18:04:41 INFO - Running main action method: create_virtualenv 18:04:41 INFO - Creating virtualenv /builds/slave/test/build/venv 18:04:41 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 18:04:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:04:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:04:41 INFO - Using real prefix '/tools/python27' 18:04:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:04:42 INFO - Installing distribute.............................................................................................................................................................................................done. 18:04:45 INFO - Installing pip.................done. 18:04:45 INFO - Return code: 0 18:04:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:45 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:04:45 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 18:04:45 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 18:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:45 INFO - 'HOME': '/Users/cltbld', 18:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:45 INFO - 'LOGNAME': 'cltbld', 18:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:45 INFO - 'MOZ_NO_REMOTE': '1', 18:04:45 INFO - 'NO_EM_RESTART': '1', 18:04:45 INFO - 'PAGER': '/bin/cat', 18:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:45 INFO - 'PWD': '/builds/slave/test', 18:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:45 INFO - 'SHELL': '/bin/bash', 18:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:04:45 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:45 INFO - 'USER': 'cltbld', 18:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:45 INFO - 'XPC_FLAGS': '0x0', 18:04:45 INFO - 'XPC_SERVICE_NAME': '0', 18:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:45 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 18:04:45 INFO - Cleaning up... 18:04:45 INFO - Return code: 0 18:04:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:45 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:04:45 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 18:04:45 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 18:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:45 INFO - 'HOME': '/Users/cltbld', 18:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:45 INFO - 'LOGNAME': 'cltbld', 18:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:45 INFO - 'MOZ_NO_REMOTE': '1', 18:04:45 INFO - 'NO_EM_RESTART': '1', 18:04:45 INFO - 'PAGER': '/bin/cat', 18:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:45 INFO - 'PWD': '/builds/slave/test', 18:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:45 INFO - 'SHELL': '/bin/bash', 18:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:04:45 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:45 INFO - 'USER': 'cltbld', 18:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:45 INFO - 'XPC_FLAGS': '0x0', 18:04:45 INFO - 'XPC_SERVICE_NAME': '0', 18:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:45 INFO - Downloading/unpacking psutil>=0.7.1 18:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:04:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:04:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:49 INFO - Installing collected packages: psutil 18:04:49 INFO - Running setup.py install for psutil 18:04:49 INFO - building 'psutil._psutil_osx' extension 18:04:49 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 18:04:49 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 18:04:50 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 18:04:50 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 18:04:50 INFO - building 'psutil._psutil_posix' extension 18:04:50 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 18:04:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:04:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:04:50 INFO - ^ 18:04:50 INFO - 1 warning generated. 18:04:50 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 18:04:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:04:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:04:50 INFO - Successfully installed psutil 18:04:50 INFO - Cleaning up... 18:04:50 INFO - Return code: 0 18:04:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:04:50 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')]} 18:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:50 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')]} 18:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:50 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:04:50 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 18:04:50 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 18:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:50 INFO - 'HOME': '/Users/cltbld', 18:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:50 INFO - 'LOGNAME': 'cltbld', 18:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:50 INFO - 'MOZ_NO_REMOTE': '1', 18:04:50 INFO - 'NO_EM_RESTART': '1', 18:04:50 INFO - 'PAGER': '/bin/cat', 18:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:50 INFO - 'PWD': '/builds/slave/test', 18:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:50 INFO - 'SHELL': '/bin/bash', 18:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:04:50 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:50 INFO - 'USER': 'cltbld', 18:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:50 INFO - 'XPC_FLAGS': '0x0', 18:04:50 INFO - 'XPC_SERVICE_NAME': '0', 18:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:04:50 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 18:04:50 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 18:04:50 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 18:04:50 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 18:04:50 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 18:04:50 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 18:04:53 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:04:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:04:53 INFO - Installing collected packages: mozsystemmonitor 18:04:53 INFO - Running setup.py install for mozsystemmonitor 18:04:53 INFO - Successfully installed mozsystemmonitor 18:04:53 INFO - Cleaning up... 18:04:53 INFO - Return code: 0 18:04:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:04:53 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')]} 18:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:53 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')]} 18:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:53 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:04:53 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 18:04:53 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 18:04:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:04:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:53 INFO - 'HOME': '/Users/cltbld', 18:04:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:53 INFO - 'LOGNAME': 'cltbld', 18:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:53 INFO - 'MOZ_NO_REMOTE': '1', 18:04:53 INFO - 'NO_EM_RESTART': '1', 18:04:53 INFO - 'PAGER': '/bin/cat', 18:04:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:53 INFO - 'PWD': '/builds/slave/test', 18:04:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:53 INFO - 'SHELL': '/bin/bash', 18:04:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:04:53 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:04:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:53 INFO - 'USER': 'cltbld', 18:04:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:53 INFO - 'XPC_FLAGS': '0x0', 18:04:53 INFO - 'XPC_SERVICE_NAME': '0', 18:04:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:53 INFO - Downloading/unpacking blobuploader==1.2.4 18:04:53 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 18:04:53 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 18:04:53 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 18:04:53 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 18:04:53 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 18:04:53 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 18:04:56 INFO - Downloading blobuploader-1.2.4.tar.gz 18:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:04:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:04:56 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 18:04:56 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 18:04:56 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 18:04:56 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 18:04:56 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 18:04:56 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 18:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:04:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:04:56 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 18:04:56 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 18:04:56 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 18:04:56 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 18:04:56 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 18:04:56 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 18:04:57 INFO - Downloading docopt-0.6.1.tar.gz 18:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:04:57 INFO - Installing collected packages: blobuploader, requests, docopt 18:04:57 INFO - Running setup.py install for blobuploader 18:04:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:04:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:04:57 INFO - Running setup.py install for requests 18:04:58 INFO - Running setup.py install for docopt 18:04:58 INFO - Successfully installed blobuploader requests docopt 18:04:58 INFO - Cleaning up... 18:04:58 INFO - Return code: 0 18:04:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:04:58 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:04:58 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 18:04:58 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 18:04:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:04:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:04:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:04:58 INFO - 'HOME': '/Users/cltbld', 18:04:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:04:58 INFO - 'LOGNAME': 'cltbld', 18:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:04:58 INFO - 'MOZ_NO_REMOTE': '1', 18:04:58 INFO - 'NO_EM_RESTART': '1', 18:04:58 INFO - 'PAGER': '/bin/cat', 18:04:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:04:58 INFO - 'PWD': '/builds/slave/test', 18:04:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:04:58 INFO - 'SHELL': '/bin/bash', 18:04:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:04:58 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:04:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:04:58 INFO - 'USER': 'cltbld', 18:04:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:04:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:04:58 INFO - 'XPC_FLAGS': '0x0', 18:04:58 INFO - 'XPC_SERVICE_NAME': '0', 18:04:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:04:58 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-vNMV0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:04:58 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-qYAng3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:04:58 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Vgl7_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:04:58 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-FvtXjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-GSzBln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-nLE88Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-yhOP8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-f0BCLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-XfxA8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-QnZZcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-IARXu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-c5i3gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-ELZtNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:04:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:04:59 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-8UJ36M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-rFCV5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-LWU12a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:00 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-P3VpT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:00 INFO - Running setup.py install for manifestparser 18:05:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozcrash 18:05:00 INFO - Running setup.py install for mozdebug 18:05:00 INFO - Running setup.py install for mozdevice 18:05:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:00 INFO - Running setup.py install for mozfile 18:05:01 INFO - Running setup.py install for mozhttpd 18:05:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozinfo 18:05:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozInstall 18:05:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for mozleak 18:05:01 INFO - Running setup.py install for mozlog 18:05:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:01 INFO - Running setup.py install for moznetwork 18:05:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for mozprocess 18:05:02 INFO - Running setup.py install for mozprofile 18:05:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for mozrunner 18:05:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Running setup.py install for mozscreenshot 18:05:02 INFO - Running setup.py install for moztest 18:05:02 INFO - Running setup.py install for mozversion 18:05:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:02 INFO - Cleaning up... 18:05:02 INFO - Return code: 0 18:05:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:02 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:05:02 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 18:05:02 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 18:05:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:05:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:02 INFO - 'HOME': '/Users/cltbld', 18:05:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:02 INFO - 'LOGNAME': 'cltbld', 18:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:02 INFO - 'MOZ_NO_REMOTE': '1', 18:05:02 INFO - 'NO_EM_RESTART': '1', 18:05:02 INFO - 'PAGER': '/bin/cat', 18:05:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:02 INFO - 'PWD': '/builds/slave/test', 18:05:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:02 INFO - 'SHELL': '/bin/bash', 18:05:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:05:02 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:05:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:02 INFO - 'USER': 'cltbld', 18:05:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:02 INFO - 'XPC_FLAGS': '0x0', 18:05:02 INFO - 'XPC_SERVICE_NAME': '0', 18:05:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-TJjAxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-bd4cUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-UFoK0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-W56dox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-8Dn7fK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Xr6G0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-RIQ873-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:03 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)) 18:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:03 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-6BMrpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-F4NPxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-5nurys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Q_hT9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-bAroLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-UvxSvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-Ipjc32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-TF6jL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-TQCNuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:04 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)) 18:05:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:04 INFO - Running setup.py (path:/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/pip-fX9Fi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 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)) 18:05:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:07 INFO - Downloading blessings-1.6.tar.gz 18:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:07 INFO - Installing collected packages: blessings 18:05:07 INFO - Running setup.py install for blessings 18:05:07 INFO - Successfully installed blessings 18:05:07 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:08 INFO - Reading from file tmpfile_stdout 18:05:08 INFO - Current package versions: 18:05:08 INFO - blessings == 1.6 18:05:08 INFO - blobuploader == 1.2.4 18:05:08 INFO - docopt == 0.6.1 18:05:08 INFO - manifestparser == 1.1 18:05:08 INFO - mozInstall == 1.12 18:05:08 INFO - mozcrash == 0.17 18:05:08 INFO - mozdebug == 0.1 18:05:08 INFO - mozdevice == 0.48 18:05:08 INFO - mozfile == 1.2 18:05:08 INFO - mozhttpd == 0.7 18:05:08 INFO - mozinfo == 0.9 18:05:08 INFO - mozleak == 0.1 18:05:08 INFO - mozlog == 3.1 18:05:08 INFO - moznetwork == 0.27 18:05:08 INFO - mozprocess == 0.22 18:05:08 INFO - mozprofile == 0.28 18:05:08 INFO - mozrunner == 6.11 18:05:08 INFO - mozscreenshot == 0.1 18:05:08 INFO - mozsystemmonitor == 0.0 18:05:08 INFO - moztest == 0.7 18:05:08 INFO - mozversion == 1.4 18:05:08 INFO - psutil == 3.1.1 18:05:08 INFO - requests == 1.2.3 18:05:08 INFO - wsgiref == 0.1.2 18:05:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:08 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:05:08 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 18:05:08 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 18:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:08 INFO - 'HOME': '/Users/cltbld', 18:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:08 INFO - 'LOGNAME': 'cltbld', 18:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:08 INFO - 'MOZ_NO_REMOTE': '1', 18:05:08 INFO - 'NO_EM_RESTART': '1', 18:05:08 INFO - 'PAGER': '/bin/cat', 18:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:08 INFO - 'PWD': '/builds/slave/test', 18:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:08 INFO - 'SHELL': '/bin/bash', 18:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:05:08 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:08 INFO - 'USER': 'cltbld', 18:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:08 INFO - 'XPC_FLAGS': '0x0', 18:05:08 INFO - 'XPC_SERVICE_NAME': '0', 18:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 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)) 18:05:08 INFO - Cleaning up... 18:05:08 INFO - Return code: 0 18:05:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05: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')]} 18:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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')]} 18:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:08 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:05:08 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 18:05:08 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 18:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:08 INFO - 'HOME': '/Users/cltbld', 18:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:08 INFO - 'LOGNAME': 'cltbld', 18:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:08 INFO - 'MOZ_NO_REMOTE': '1', 18:05:08 INFO - 'NO_EM_RESTART': '1', 18:05:08 INFO - 'PAGER': '/bin/cat', 18:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:08 INFO - 'PWD': '/builds/slave/test', 18:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:08 INFO - 'SHELL': '/bin/bash', 18:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:05:08 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:08 INFO - 'USER': 'cltbld', 18:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:08 INFO - 'XPC_FLAGS': '0x0', 18:05:08 INFO - 'XPC_SERVICE_NAME': '0', 18:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:08 INFO - Downloading/unpacking jsonschema 18:05:08 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 18:05:08 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 18:05:08 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 18:05:08 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 18:05:08 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 18:05:08 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 18:05:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:11 INFO - Downloading/unpacking functools32 (from jsonschema) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:11 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:11 INFO - warning: no files found matching '*.txt' 18:05:11 INFO - no previously-included directories found matching 'build' 18:05:11 INFO - no previously-included directories found matching 'dist' 18:05:11 INFO - no previously-included directories found matching '.git*' 18:05:11 INFO - Installing collected packages: jsonschema, functools32 18:05:11 INFO - Running setup.py install for functools32 18:05:11 INFO - warning: no files found matching '*.txt' 18:05:11 INFO - no previously-included directories found matching 'build' 18:05:11 INFO - no previously-included directories found matching 'dist' 18:05:11 INFO - no previously-included directories found matching '.git*' 18:05:11 INFO - Successfully installed jsonschema functools32 18:05:11 INFO - Cleaning up... 18:05:11 INFO - Return code: 0 18:05:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:05:11 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')]} 18:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:11 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')]} 18:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:11 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 0x10308ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10309b420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb470592500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, '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.b2amUv0IRK/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.7H8ErLcac7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/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 18:05:11 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 18:05:11 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 18:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:11 INFO - 'HOME': '/Users/cltbld', 18:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:11 INFO - 'LOGNAME': 'cltbld', 18:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:11 INFO - 'MOZ_NO_REMOTE': '1', 18:05:11 INFO - 'NO_EM_RESTART': '1', 18:05:11 INFO - 'PAGER': '/bin/cat', 18:05:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:11 INFO - 'PWD': '/builds/slave/test', 18:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:11 INFO - 'SHELL': '/bin/bash', 18:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:05:11 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:11 INFO - 'USER': 'cltbld', 18:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:11 INFO - 'XPC_FLAGS': '0x0', 18:05:11 INFO - 'XPC_SERVICE_NAME': '0', 18:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:12 INFO - Downloading/unpacking flake8 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:12 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 18:05:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:05:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:05:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:15 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:05:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:15 INFO - no previously-included directories found matching 'docs/_build' 18:05:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:05:15 INFO - Running setup.py install for pep8 18:05:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:05:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:05:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:05:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:05:16 INFO - no previously-included directories found matching 'docs/_build' 18:05:16 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:05:16 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:05:16 INFO - Cleaning up... 18:05:16 INFO - Return code: 0 18:05:16 INFO - Running post-action listener: _resource_record_post_action 18:05:16 INFO - Running post-action listener: _start_resource_monitoring 18:05:16 INFO - Starting resource monitoring. 18:05:16 INFO - ##### 18:05:16 INFO - ##### Running install step. 18:05:16 INFO - ##### 18:05:16 INFO - Running pre-action listener: _resource_record_pre_action 18:05:16 INFO - Running main action method: install 18:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:16 INFO - Reading from file tmpfile_stdout 18:05:16 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:16 INFO - Reading from file tmpfile_stdout 18:05:16 INFO - Output received: 18:05:16 INFO - Usage: mozinstall [options] installer 18:05:16 INFO - Options: 18:05:16 INFO - -h, --help show this help message and exit 18:05:16 INFO - -d DEST, --destination=DEST 18:05:16 INFO - Directory to install application into. [default: 18:05:16 INFO - "/builds/slave/test"] 18:05:16 INFO - --app=APP Application being installed. [default: firefox] 18:05:16 INFO - mkdir: /builds/slave/test/build/application 18:05:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:05:47 INFO - Reading from file tmpfile_stdout 18:05:47 INFO - Output received: 18:05:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:05:47 INFO - Running post-action listener: _resource_record_post_action 18:05:47 INFO - ##### 18:05:47 INFO - ##### Running run-tests step. 18:05:47 INFO - ##### 18:05:47 INFO - Running pre-action listener: _resource_record_pre_action 18:05:47 INFO - Running main action method: run_tests 18:05:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:47 INFO - 2016-05-06 18:05:47.956 screenresolution[1639:7663] starting screenresolution argv=screenresolution get 18:05:47 INFO - 2016-05-06 18:05:47.959 screenresolution[1639:7663] Display 0: 1600x1200x32@60 18:05:47 INFO - 2016-05-06 18:05:47.965 screenresolution[1640:7666] starting screenresolution argv=screenresolution list 18:05:47 INFO - Available Modes on Display 0 18:05:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:48 INFO - Intel Iris: 18:05:48 INFO - Chipset Model: Intel Iris 18:05:48 INFO - Type: GPU 18:05:48 INFO - Bus: Built-In 18:05:48 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:48 INFO - Vendor: Intel (0x8086) 18:05:48 INFO - Device ID: 0x0a2e 18:05:48 INFO - Revision ID: 0x0009 18:05:48 INFO - Displays: 18:05:48 INFO - Display: 18:05:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:48 INFO - Display Serial Number: 1600x1200 60 18:05:48 INFO - Main Display: Yes 18:05:48 INFO - Mirror: Off 18:05:48 INFO - Online: Yes 18:05:48 INFO - Rotation: Supported 18:05:48 INFO - Return code: 0 18:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:48 INFO - Python 2.7.3 18:05:48 INFO - Return code: 0 18:05:48 INFO - grabbing minidump binary from tooltool 18:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:05:48 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 0x102f2ae00>, '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 0x102d93db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f25b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:48 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 18:05:48 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 18:05:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:48 INFO - Return code: 0 18:05:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:05:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:05:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:05:49 INFO - Return code: 0 18:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--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 18:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0032 --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 18:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2amUv0IRK/Render', 18:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:49 INFO - 'HOME': '/Users/cltbld', 18:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:49 INFO - 'LOGNAME': 'cltbld', 18:05:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:49 INFO - 'MOZ_NO_REMOTE': '1', 18:05:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:49 INFO - 'NO_EM_RESTART': '1', 18:05:49 INFO - 'PAGER': '/bin/cat', 18:05:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:49 INFO - 'PWD': '/builds/slave/test', 18:05:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:49 INFO - 'SHELL': '/bin/bash', 18:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7H8ErLcac7/Listeners', 18:05:49 INFO - 'TMPDIR': '/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/', 18:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:49 INFO - 'USER': 'cltbld', 18:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:49 INFO - 'XPC_FLAGS': '0x0', 18:05:49 INFO - 'XPC_SERVICE_NAME': '0', 18:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0032', '--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 18:05:49 INFO - mozversion application_buildid: 20160506161844 18:05:49 INFO - mozversion application_changeset: ddb972fb7c3721d82fb05774bbaf41806f13e545 18:05:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:05:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:49 INFO - mozversion application_name: Firefox 18:05:49 INFO - mozversion application_remotingname: firefox-dev 18:05:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:05:49 INFO - mozversion application_vendor: Mozilla 18:05:49 INFO - mozversion application_version: 48.0a2 18:05:49 INFO - mozversion platform_buildid: 20160506161844 18:05:49 INFO - mozversion platform_changeset: ddb972fb7c3721d82fb05774bbaf41806f13e545 18:05:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:05:49 INFO - mozversion platform_version: 48.0a2 18:05:49 INFO - using testdate: 1462583149 18:05:49 INFO - actual date: 1462583149 18:05:49 INFO - starting webserver on 'localhost:49276' 18:05:49 INFO - SUITE-START | Running 2 tests 18:05:49 INFO - TEST-START | dromaeo_css 18:05:49 INFO - operating with platform_type : mac_ 18:05:49 INFO - Initialising browser for dromaeo_css test... 18:05:49 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpbdooX7/profile http://localhost:49276/getInfo.html) 18:05:51 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 18:05:51 INFO - PROCESS | 1658 | colorDepth:24 18:05:51 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 18:05:51 INFO - PROCESS | 1658 | __metrics 18:05:52 INFO - PROCESS | 1658 | 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" 18:05:52 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:05:52 INFO - TEST-INFO | 1658: exit 0 18:05:52 INFO - Browser initialized. 18:05:52 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:52 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpbdooX7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:04 INFO - PROCESS | 1660 | RSS: Main: 211480576 18:06:04 INFO - PROCESS | 1660 | 18:06:56 INFO - PROCESS | 1660 | 1462583216275 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] 18:09:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 18:09:08 INFO - PROCESS | 1660 | RSS: Main: 380968960 18:09:08 INFO - PROCESS | 1660 | 18:12:11 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 18:12:12 INFO - PROCESS | 1660 | RSS: Main: 357060608 18:12:12 INFO - PROCESS | 1660 | 18:15:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 18:15:15 INFO - PROCESS | 1660 | RSS: Main: 361033728 18:15:15 INFO - PROCESS | 1660 | 18:18:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 18:18:17 INFO - PROCESS | 1660 | RSS: Main: 360923136 18:18:17 INFO - PROCESS | 1660 | 18:21:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 18:21:21 INFO - PROCESS | 1660 | RSS: Main: 328007680 18:21:21 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 18:24:24 INFO - PROCESS | 1660 | RSS: Main: 367702016 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | __start_tp_report 18:24:24 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 18:24:24 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 18:24:24 INFO - PROCESS | 1660 | |i|pagename|runs| 18:24:24 INFO - PROCESS | 1660 | |0;dojo.html;3203.4393981053317;3460;3485.1461391958965;3485.3900567400697;3527.7354198435123;5681.545476361892;5682.744276507557;5702.74337654806;5767.884642307162;5771.336296325924;5816.738246778895;5838.649681019142;5842.795502157425;5860.033094539396;5875.823725288237;5168.819091331804;5219.608529360295;5256.8160114396005;5264.684118952856;5370.785168593271;5316.175992721115;5317.973410132963;5318.202269659562;5321.148616221405;5333.226682131089;5247.632665713401;5289.127293996506;5303.310569625944;5306.363236411636;5310.70791106489;4437.622802061813;5343.759530821122;5352.785888564453;5357.142857142839;5399.487048730364;5109.6167787415725;5138.665986710889;5144.254083157958;5235.65968212065;5240.161574148118;5619.438056194389;5654.575906807006;5658.688772117532;5662.462066103713;5756.395578464285;6146.815595532141;6155.846103847395;6180.783672571439;6185.41238582334;6191.690415479253;5317.361916569997;6121.66330851803;6172.598781079216;6187.5668703190795;6191.102290167901;5886.735096920649;5894;5910.202122713422;5931.991561434566;5944.346121926628;6298.590591611531;6317.684115794192;6322.399372059647;6326.304106548276;6335.303116657164;5973.074173503114;6064.792498024651;6093.786717464867;6184.5980011299125;6195.31851496335;6140.13616995768;6240.31356550779;6241.469475094577;6243.531735119885;6244.594101383395;6120.296165940974;6163.96918015407;6214.8135555933395;6215.875682486325;6222.253329600387;6134.693265336805;6151.169592105009;6157.907631385533;6179.721912513947;6192.442680158717;4908.828191013297;4940.925886111711;4951.331570237971;4951.925721114186;4956.677815941952;3811.9047023824073;4460.665450091256;4483.439570053743;4504.684672072924;4513.5486451355255;5774.94225057744;5790.478856902899;5884.293884733859;5911.438413350725;5919.3192782829665;6105.145279660851;6157.538184636171;6183.072539119078;6209.534284928558;6215.627062376272;4532.4334458192725;5771.711414429262;5821.330546987151;5825.970870145622;5848.707564621752;5312.893742125136;5390.838274851761;5408.242846001563;5452.781888724407;5477.972610137003;6352.047192921099;6362.45919096882;6401.1678481798;6410.64741439216;6427.710753016126;5563.9224808513;6160.199174107337;6192.318844926965;6193.102000210034;6195.628262304276;6272.121902933684;6340.334264902119;6373.904391434225;6428.260750013808;6438.967805161132;6158.3225845157895;6162.106494558354;6180.845478863064;6183.81448556544;6188.0717892314715;5993.880122397615;6105.5420843437805;6155.538334624832;6171.876562468815;6184.5052395809125;6036.396360363929;6074.939250607437;6075.696215189135;6075.817725468243;6093.969530152321;6018.187544681412;6089.299730530868;6090.939090609215;6152.323244443197;6154.1999540059505;5991.610545314541;5999.730012149376;5999.880002400015;6017.308009578955;6023.939760602513;6084.726187321493;6103.572749907641;6105.297890742621;6114.847128821815;6125.356837532173;5778.913316300343;5841.766329346949;5906.940930590639;5915.053591425351;5979.730912109053 18:24:24 INFO - PROCESS | 1660 | |1;ext.html;504.3745755263474;541.4720647368816;561.0630247486697;573.5841514901699;601.9796445025684;31965.52051719226;32422;32558.83720581397;39644.60355396445;40567.797161014154;45874.311885321746;46032.76983615077;46321.305180422234;46373.7681311593;46431;9657.565409556586;9881.703548893545;9890.30767846251;9908.256880733938;9925.652602158925;8407.495550266973;8695.95652021739;11739.413029348498;12018.579349722762;12026.579069732561;9906.405615663047;11821.586244481445;11930.463129159165;11931;11937.641870743892;8314.251717345467;11834.408279586029;11896.702582435422;11903.047756179485;11916.28502289861;11739.236949598335;11971.521139154424;11980.880191198064;11988.220765650205;11993.220440671328;39842.40236396456;40425.595744042774;40493;40499;40502.594974050175;3224.3242703318238;3294.6540613235506;3370.3933292007428;3491.825408729553;3571.053670777246;28000.439991200266;28141;28736.994205202718;28906.71093289061;28948;2784.763295119917;2804.42509285598;2811.845348505831;2846;2888.422315536901;485435;487247;487838;488482;488528.99999999645;36429;37051.62948370536;37242.25515489675;37243.4413483798;67633;35859.64140358563;36075.63924360723;36195.819020904724;36343.09142271464;36359.90920272396;15646.9217653911;15796.684066318609;15840.44558440449;15853.445129420415;15860.127692976997;12386.442610082568;12750.61748147557;13367.398467069006;13468.057235993387;13490.595282141356;8289.336853051742;8416.579171041423;8432.325413966868;8471.449355791854;8539.316854651614;3411.6417776133644;3442.896713098611;3447.3967055765143;3462.515247865301;3478.5477887874413;893.7944272817289;1015.9339642923334;1045.5347370420243;1071.405370019631;1103.4041617526607;30728;31018;31172;31406;31898.2025449361;1094.499069625901;1132.6149109302876;1145.604766355606;1188.197966372695;1208.238809549978;15064.698706026038;15243.771343429857;15448.922755386151;15515;15630.452934147477;579495;583809;586797.3300667432;586926;587535;62872.68563657244;65030.67484662641;65083.34916650868;65099;65374.67312663501;1337.317967836391;1345.7510360583324;1517.0973270903794;1519.6352875309624;1527.7861099446086;29052.56421153642;29499.410011799213;29807.701922980494;30403.2399190022;30440.695593043787;55473;56286;56389.43610564006;56535.151972721265;56633.433665662815;519412.4029379829;519873;520938;522656;524070;24384.756152438247;27670.44659106847;28071.438571228053;28306.2923426916;28366.574501382907;43077.87577031701;44433.77783111064;44517.887052823935;44646.77676611596;45335.31997019982;35636.64363356333;35955.640443596276;36456.453153202194;37214.44178337381;37303.253934920605;36724.0818979517;36926.261474770894;37033.259334813694;37640.43539346857;37770;34831;38303.808480957414;39422;39863.00342491461;40716.79641601773;27393.452130957667;28317;30366.696333036387;32075.67924320727;32469;20890.373288801362;25289.494210116063;25363.746362536876;25722.871385642953;25931.481370372865 18:24:24 INFO - PROCESS | 1660 | |2;jquery.html;407.922494726002;410.8110269276133;415.4764996104907;419.2474508257677;420.6697742272317;13906;19886.801131988675;20566.971651417425;20649.896750516244;20710.896445517752;15972.600684982881;16462.094584797833;20156.496087597818;20637.86491742957;20696.586068278662;5600.52395546378;5685.744141513642;5865.061590145578;5880.441358070976;5890.941090589104;4124.484439445069;5750.971245143768;5816.243888294516;5842;5847.532197424217;6179.104029915659;6486.2540807807;6489.870202595946;6492.772752953648;6496.870062598745;6347.904781428282;6405.071264666643;6429.839254018617;6429.967850160766;6436.195475565554;5497.120460726304;5608.9439105609235;5643.68959707216;5656.490915817596;5656.519195868356;23966.52066958651;24404.755952440424;24728.629070563955;24852.3786905327;25408.237752867444;1981.4352909420745;2228.353777404551;2354.2348736660483;2402.699662542182;2418.37122348189;9484.952575237148;10592.735181620445;14557.417703291856;15162.924185379003;15250.618734531725;1711.3582406597525;1756.7364895265684;1765.2674140231788;1784.2595322940963;1800.288885890066;112275.87724122735;114966.27550586748;118479;121049.78950210473;125942;10793.974572415607;11472.713182170513;11515.25150865191;11533.596324128615;11589.536418543164;13182.538611148564;15068.095914245181;15174.544763657108;15179.316930738143;15201.6199595011;7897.644605992629;8250.669973201006;8252;8268.338532917352;8730.563471826383;7787.844243115106;8150.592470376458;8189.754307370788;8695.869561956575;8878.378513504122;4629.305604159403;4662.090892275974;4676.965519998744;4694.43666759991;4717.561143851159;1946.0756140832991;1985.414302780683;2000.57987822556;2006.3178519303508;2009.7286866272866;729.6607077708891;865.2904618213005;867.2714919467677;897.3852910756153;1021.2085633633993;18923.716144257847;19295.710564341545;19464.221431142596;19540.316088937103;19568.70646940297;853.7865533616596;916.867054277126;979.7060881735451;992.538469611634;1002.1281485107856;8678.522681252593;10423.374597524173;10459.895401046044;10471.94764026175;10536.051755342056;658735;660074;663961;678908;681975;131377.34311328572;136299.31850340884;137041;137168;142721;895.1943251074085;1048.1876545677162;1063.6330465989222;1069.7218723131887;1086.9782604348027;17407.390741323994;17448.47654570365;17681.823181768017;17684.027378494306;17697.734533982257;48278;50457;51685.48314516957;53690;54801.17798233109;68924;73275.26724732685;73558;75671.86492202732;77288.61355693186;7914.445988780961;8229.177082291722;8293.751187464386;8359.205875441823;8569.742907712778;9251.907480925374;11958.402079895797;12860.742785144057;13017.609471715863;13278.535251266545;10884.61903833362;10949.890501094887;11159.832602510807;11262.7747445049;11440.027597654293;11057.447127643425;11184.496697648789;11283.66149015531;11427.200095993534;11621.47703353368;11190.94404527972;11351.205415620827;11484.540618375171;11607.477663505331;11747.118966077749;8330.333573314;8360.66557337724;8400.495970241805;8423.368247381348;8568.314534837322;10587.4706264688;11039.39283339424;11246.381449020382;11405.600803971822;11569.074474042225 18:24:24 INFO - PROCESS | 1660 | |3;mootools.html;1333.8799508044274;1397.3781667158116;1621.5054408405435;1846.6675998320297;2032.0449388787274;7939.801504962379;8716.041235464101;8844.911550884497;8868.512231827246;8877.422967507107;8534.615942282597;8806.339524535653;8813.559322033905;8833.116688331163;8836.91163088369;2157.687135365371;2215.7784221577795;2224.154821167962;2392.9999999999955;2423.006567307404;2202.493426511903;2245.8989345479413;2448.5837407640743;2515.610080437535;2516.6099254615565;2102.6740855167473;2410.5901996660523;2463.5319289335057;2465.174166654164;2468.5062987402503;2139.70044193813;2417.1177520205174;2421.806255499556;2425.199684104259;2425.871969534175;2401.147592604618;2468.8642124683133;2470.3947532854568;2470.938226544333;2474.5894839941243;8646.308295336357;8726.476411415335;8734.432261903019;8737.344699147527;8742.344324175712;5198.298229739013;5371.194320851864;5386;5413.729313534308;5423.891522169534;3429.554157959449;3518.7712798668003;3601.7838929664304;3615.529981102466;3625.782453052825;1042.6402891002592;1052.147760314148;1054.8575942247776;1074.8280275155937;1098.549594666191;12061.155719099666;12092.214006089576;12094.516219351215;12110.939445302716;12112.757744845141;4758.3606232013635;4771.355866957986;4790.952090479121;4850.975745121252;4902.975485122552;10124.74688132788;10128.898711012796;10132.797344053077;10137.69586912379;10146;5374.946250537445;5381.165919282517;5401.054815407288;5432.02223599748;5446.128619420873;4391.912161756747;4549.567791059844;4926.3842019747535;4935.703857768545;4937.679050861754;3394.7963122212745;4037.6971727120585;4094.61101195386;4100.097978444737;4112.897177570585;1336.3719052045524;1360.6734383748028;1361.360160724458;1370.383327502608;1374.6494643865951;783.2011348424575;851.3061854588615;904.3759805734022;908.2824568590871;968.7771812483028;4932.358793356841;5777.62445441045;5900.911486327708;6031.306399764083;6041.154238406627;962.4417837654144;974.5224839828433;992.5186284651938;1001.5743309093606;1005.607812952949;3770.057485628593;3853.1715681128694;3857.8456861725217;3858.6141385861188;3958.6041395860184;9132.908670913339;10242.334248273839;10591.258611897094;10681.092107170978;10736;10696.144308455305;10719.732006699895;10720.946395267974;10740.838887416696;10773.83839242412;7754.534727916231;8230.588470576567;8348;8477.830443391098;8503.319734421231;5645.887082258414;5712.80005199816;5748.770049198152;5820.505257053198;5991.7603295867675;9247.72256832296;9315.301352398461;9440.527973601154;9613.471259080818;9622.13400793932;9590.856137158087;9594.376365536218;9799.706008819747;9857.507124643882;9891.653792117242;6666.699998499982;6839.213490448662;7373.963130184315;7417.443691723034;7486.7005319786595;8118.269355757774;8211.58942052907;8297.95851020741;8304.29413499835;8328.042275138436;7142.250063743232;7258.98374227598;7303.014093097577;7303.452241081834;7420.6289685516585;7100.325469080429;7160.462965277521;7212.675429605575;7255.274472552703;7301.452391070585;6746.212253752661;6858.965705171642;6891.552049116792;6965.129358830146;6979.987901754115;4729.763511824326;4807.591354734747;4823.2282834746275;4916.8770780730765;4974.726390048582;4855.393075865517;4887.144749668722;4898.902021959612;4948.826791062295;5095.668781529189 18:24:24 INFO - PROCESS | 1660 | |4;prototype.html;322.6579825385092;326.95749552558163;332.6307798343839;338.8542926541586;339.2553345406831;6668.199816022078;6687.030380594817;6720.529562930591;6784.084148639932;6791.626460544668;6672.065910772496;6683.498737594676;6708.329167083301;6716.83207919801;6730.293319201487;4686.41419822523;4787.784549695272;4829.1307564638355;4859.416869975608;4923.433805112404;4900.534449227318;4904.0191961607825;4943.604511639078;4943.9752801235945;4948.678335908155;4929.605631549485;4943.876403089916;4951.628627852908;4955.801767929279;4958.950410495903;4693.530646935296;4788.186008378567;4885.218365061609;4905.754712264373;4922.261660750916;4186.769727664977;4188.225178341986;4190.245755763961;4193.182329445759;4197.517285512175;5759.798407055733;5835.824925252206;5868.002439585281;6319.620822750604;6422.421982021594;5889.823305300848;5900.350961394243;5903.616264942765;5912.793052243193;5924.792632257893;6892.896606550906;7134.17956934954;7146.106736657918;7155.642217889084;7161;4765.976170119127;4777.331173635694;4785.64107691921;4800.831970881038;4814.7351895645725;6239.469645080174;7567.31894129531;7637.427192960495;7641.656125474367;7650.42621803367;4735.502772208937;4834.347363105936;4851.830185943475;4861.51384861511;4861.829835955796;4919.0407870464915;4930.704157750475;4931.3589233399425;4937.407511098691;4944.653874228769;5663.405342439067;5868.911966320508;5891.3224979127945;5895.35151133375;5905.261842269716;7197.280271972761;7200.531965422231;7203.89194162088;7210.495265331377;7238.819029524304;1662.6009757658076;1703.3186725309727;1704.7442883567317;1709.2223038517445;1709.991450042742;1034.462079718542;1047.3349423116376;1093.9398333091597;1102.4267380961855;1122.9157813164045;6604.2074951005225;6724.3948044675235;6724.831879203059;6734.225564060098;6758.425533829582;6906.447484201251;7551.962240188764;7566;7567.243275672498;7582.658780354787;6775.3224677532835;6784.118064651564;6795.456363491007;6811.012403201508;6820.3861652451515;3774.8490060397826;3940.192260586573;4001.2797696414923;4004.0790618157985;4008.0581063450068;6519.8044058678315;7050.435965122778;7298.343149116605;7840.529568225925;7915.604219788987;7762.7671169865;7768.844623107506;7772.611369431505;7773.183815699384;7776.222377762291;6423.903641445476;7872.173421790744;7882.172371900868;7886.05367355898;7901.091374492007;7181.928180718126;7445.9627701861145;7503.4372422069655;7524.247575242651;7528.924710752823;7224.819379515554;7280.271972802678;7296.671649775879;7299.963500182465;7342.302481264272;7864;7876.330511906552;7888.132305446282;7896.210378962058;7903.881441778263;4660.86017419478;4690.179218636793;4726.787294571684;4731.172044892061;4732.574068333866;7176.390006849476;7465.664045118091;7481.588512631857;7497.550146991198;7524.5861477619255;7108.395786358011;7112.431005519443;7143.071400717873;7153.463490238149;7160.713571457083;7145.4640901931525;7150.5352152109945;7159.60622165765;7167.928320716935;7190.460715446257;7020.017197592239;7022.332878376546;7026.19198792125;7040.718371265092;7052.259512751292;6807.965960170168;7107.076080109553;7114.6442677866935;7125.429965602843;7149.821254468472;6647.434968027578;6724.428423583854;6747.257801641914;6858.862822743617;6890.10428644273 18:24:24 INFO - PROCESS | 1660 | |5;yui.html;664.1266734244471;672.247083266741;683.0948992584825;721.4084450750383;734.0200831889427;15041.097534147944;15290.541283761491;17186.312547498103;17493.650126997483;17612.82387176128;13432.798508022388;15944.681106377895;16036.198190090507;16042.876998610102;16070.678586428265;749.4379215588309;759.7302957450108;902.2511315608047;943.3302355327726;951.2485136741966;911.5487833522383;914.6341463414655;914.7713071732067;916.5508900638672;916.6287653500343;972.8200282947641;972.8735264415616;972.9783727086531;973.5667627905585;981.9705408837747;779.6764342797732;787.4173111897155;793.5635400529743;966.2656381150306;979.4612962870428;579.9879210777178;714.4998501049234;715.1525442350779;717.666285177395;717.9425645948364;15109.168995705231;15154.242287885561;15155.924220378938;15191.544253672408;15224.543263702106;12239.082068844784;12295;12339.691507712289;12412.937935310265;12571.685707857285;9934.850977235346;10015;10114.848277275847;10408.115310198642;10831.079358254558;3498.5276987606603;3517.191046059421;3540.539729835131;3545.8936231912826;3591.838367273453;83410.58294708548;83421.32629389696;84636.57681711613;84794;84814.57592712059;9395.90604093964;9493.715188544355;9546.522673866419;9667.903320966841;9722.756931076638;17279;20821.791782082288;21074;21278.574428511343;21344.252951146947;6835.897461538081;6927.099477067949;7026.824329391806;7139.607321597258;7246.673899674527;4010.6590939770444;4058.6753059755147;4075.59244075596;4089.7137200395687;4124.670026397881;3576.1238496567985;3629.9291708945925;3643.1620727232885;3643.216708407705;3654.7258955578436;629.2826178156905;636.38270877249;645.9257185423736;647.970841312142;648.9837754056186;538.6139402447119;541.0172312997261;556.7355506134554;600.3636145685588;621.1118101115358;7767.689292428239;7979.84040319202;8044.839103217902;8148.022237331558;8180.222878826502;533.6024661627035;551.1209620655048;596.0135974961446;613.1324176290589;613.6318209074519;2954.9113526594238;3621.402468592653;3648.635136486383;3808.485854409619;3920.5491368492417;87756;89236;90204.6469302961;90831.09168908227;90904.54547727351;71440.28559714441;71718.64140679264;73885.52228955498;75142.24857751353;75749.6212518945;66470.66764666242;66913.33086669169;66973.33026669768;68425;69218;8242.134575869448;8299.543525106177;8400.453970492143;8401.495910245405;8711.651533938571;44570.55429445664;47044.52955470538;47824.52175478201;47924;48776;63298.36701632925;64305.356946431806;64412.35587644063;65621.34378656153;66913.66543167253;10404.531796069045;10530.631427899987;10692.411917344622;10732;11265.57790075105;11844.111691622991;11863.703407414883;11890.821637675615;12060.336681482602;12137.089718270989;11055.778884422427;11126.49930753102;11166.44167791591;11371.43142842838;11539.36533490666;11023.779524409627;11194.384308863091;11218.214724969173;11263.324200547993;11335;10775.137988960707;10917.23579349438;11252.399232245603;11277.774444511228;11907.285562866256;9505.906820715707;9546.665866694633;9884.555195016384;9955.601775929172;10103.54534045955;9522.52387380642;9580.377275477073;9648.807023859625;9661.13049825519;9860.260480464138 18:24:24 INFO - PROCESS | 1660 | __end_tp_report 18:24:24 INFO - PROCESS | 1660 | __start_cc_report 18:24:24 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1527 18:24:24 INFO - PROCESS | 1660 | __end_cc_report 18:24:24 INFO - PROCESS | 1660 | __startTimestamp1462584264547__endTimestamp 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | ------- Summary: start ------- 18:24:24 INFO - PROCESS | 1660 | Number of tests: 6 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5744.10 Median:5979.73 stddev:636.65 (10.6%) stddev-sans-first:606.60 18:24:24 INFO - PROCESS | 1660 | Values: 3203.4 3460.0 3485.1 3485.4 3527.7 5681.5 5682.7 5702.7 5767.9 5771.3 5816.7 5838.6 5842.8 5860.0 5875.8 5168.8 5219.6 5256.8 5264.7 5370.8 5316.2 5318.0 5318.2 5321.1 5333.2 5247.6 5289.1 5303.3 5306.4 5310.7 4437.6 5343.8 5352.8 5357.1 5399.5 5109.6 5138.7 5144.3 5235.7 5240.2 5619.4 5654.6 5658.7 5662.5 5756.4 6146.8 6155.8 6180.8 6185.4 6191.7 5317.4 6121.7 6172.6 6187.6 6191.1 5886.7 5894.0 5910.2 5932.0 5944.3 6298.6 6317.7 6322.4 6326.3 6335.3 5973.1 6064.8 6093.8 6184.6 6195.3 6140.1 6240.3 6241.5 6243.5 6244.6 6120.3 6164.0 6214.8 6215.9 6222.3 6134.7 6151.2 6157.9 6179.7 6192.4 4908.8 4940.9 4951.3 4951.9 4956.7 3811.9 4460.7 4483.4 4504.7 4513.5 5774.9 5790.5 5884.3 5911.4 5919.3 6105.1 6157.5 6183.1 6209.5 6215.6 4532.4 5771.7 5821.3 5826.0 5848.7 5312.9 5390.8 5408.2 5452.8 5478.0 6352.0 6362.5 6401.2 6410.6 6427.7 5563.9 6160.2 6192.3 6193.1 6195.6 6272.1 6340.3 6373.9 6428.3 6439.0 6158.3 6162.1 6180.8 6183.8 6188.1 5993.9 6105.5 6155.5 6171.9 6184.5 6036.4 6074.9 6075.7 6075.8 6094.0 6018.2 6089.3 6090.9 6152.3 6154.2 5991.6 5999.7 5999.9 6017.3 6023.9 6084.7 6103.6 6105.3 6114.8 6125.4 5778.9 5841.8 5906.9 5915.1 5979.7 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:65726.58 Median:19154.77 stddev:142243.70 (742.6%) stddev-sans-first:142558.43 18:24:24 INFO - PROCESS | 1660 | Values: 504.4 541.5 561.1 573.6 602.0 31965.5 32422.0 32558.8 39644.6 40567.8 45874.3 46032.8 46321.3 46373.8 46431.0 9657.6 9881.7 9890.3 9908.3 9925.7 8407.5 8696.0 11739.4 12018.6 12026.6 9906.4 11821.6 11930.5 11931.0 11937.6 8314.3 11834.4 11896.7 11903.0 11916.3 11739.2 11971.5 11980.9 11988.2 11993.2 39842.4 40425.6 40493.0 40499.0 40502.6 3224.3 3294.7 3370.4 3491.8 3571.1 28000.4 28141.0 28737.0 28906.7 28948.0 2784.8 2804.4 2811.8 2846.0 2888.4 485435.0 487247.0 487838.0 488482.0 488529.0 36429.0 37051.6 37242.3 37243.4 67633.0 35859.6 36075.6 36195.8 36343.1 36359.9 15646.9 15796.7 15840.4 15853.4 15860.1 12386.4 12750.6 13367.4 13468.1 13490.6 8289.3 8416.6 8432.3 8471.4 8539.3 3411.6 3442.9 3447.4 3462.5 3478.5 893.8 1015.9 1045.5 1071.4 1103.4 30728.0 31018.0 31172.0 31406.0 31898.2 1094.5 1132.6 1145.6 1188.2 1208.2 15064.7 15243.8 15448.9 15515.0 15630.5 579495.0 583809.0 586797.3 586926.0 587535.0 62872.7 65030.7 65083.3 65099.0 65374.7 1337.3 1345.8 1517.1 1519.6 1527.8 29052.6 29499.4 29807.7 30403.2 30440.7 55473.0 56286.0 56389.4 56535.2 56633.4 519412.4 519873.0 520938.0 522656.0 524070.0 24384.8 27670.4 28071.4 28306.3 28366.6 43077.9 44433.8 44517.9 44646.8 45335.3 35636.6 35955.6 36456.5 37214.4 37303.3 36724.1 36926.3 37033.3 37640.4 37770.0 34831.0 38303.8 39422.0 39863.0 40716.8 27393.5 28317.0 30366.7 32075.7 32469.0 20890.4 25289.5 25363.7 25722.9 25931.5 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:37019.58 Median:13160.38 stddev:111205.60 (845.0%) stddev-sans-first:111483.58 18:24:24 INFO - PROCESS | 1660 | Values: 407.9 410.8 415.5 419.2 420.7 13906.0 19886.8 20567.0 20649.9 20710.9 15972.6 16462.1 20156.5 20637.9 20696.6 5600.5 5685.7 5865.1 5880.4 5890.9 4124.5 5751.0 5816.2 5842.0 5847.5 6179.1 6486.3 6489.9 6492.8 6496.9 6347.9 6405.1 6429.8 6430.0 6436.2 5497.1 5608.9 5643.7 5656.5 5656.5 23966.5 24404.8 24728.6 24852.4 25408.2 1981.4 2228.4 2354.2 2402.7 2418.4 9485.0 10592.7 14557.4 15162.9 15250.6 1711.4 1756.7 1765.3 1784.3 1800.3 112275.9 114966.3 118479.0 121049.8 125942.0 10794.0 11472.7 11515.3 11533.6 11589.5 13182.5 15068.1 15174.5 15179.3 15201.6 7897.6 8250.7 8252.0 8268.3 8730.6 7787.8 8150.6 8189.8 8695.9 8878.4 4629.3 4662.1 4677.0 4694.4 4717.6 1946.1 1985.4 2000.6 2006.3 2009.7 729.7 865.3 867.3 897.4 1021.2 18923.7 19295.7 19464.2 19540.3 19568.7 853.8 916.9 979.7 992.5 1002.1 8678.5 10423.4 10459.9 10471.9 10536.1 658735.0 660074.0 663961.0 678908.0 681975.0 131377.3 136299.3 137041.0 137168.0 142721.0 895.2 1048.2 1063.6 1069.7 1087.0 17407.4 17448.5 17681.8 17684.0 17697.7 48278.0 50457.0 51685.5 53690.0 54801.2 68924.0 73275.3 73558.0 75671.9 77288.6 7914.4 8229.2 8293.8 8359.2 8569.7 9251.9 11958.4 12860.7 13017.6 13278.5 10884.6 10949.9 11159.8 11262.8 11440.0 11057.4 11184.5 11283.7 11427.2 11621.5 11190.9 11351.2 11484.5 11607.5 11747.1 8330.3 8360.7 8400.5 8423.4 8568.3 10587.5 11039.4 11246.4 11405.6 11569.1 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5678.68 Median:4929.37 stddev:3165.59 (64.2%) stddev-sans-first:3157.63 18:24:24 INFO - PROCESS | 1660 | Values: 1333.9 1397.4 1621.5 1846.7 2032.0 7939.8 8716.0 8844.9 8868.5 8877.4 8534.6 8806.3 8813.6 8833.1 8836.9 2157.7 2215.8 2224.2 2393.0 2423.0 2202.5 2245.9 2448.6 2515.6 2516.6 2102.7 2410.6 2463.5 2465.2 2468.5 2139.7 2417.1 2421.8 2425.2 2425.9 2401.1 2468.9 2470.4 2470.9 2474.6 8646.3 8726.5 8734.4 8737.3 8742.3 5198.3 5371.2 5386.0 5413.7 5423.9 3429.6 3518.8 3601.8 3615.5 3625.8 1042.6 1052.1 1054.9 1074.8 1098.5 12061.2 12092.2 12094.5 12110.9 12112.8 4758.4 4771.4 4791.0 4851.0 4903.0 10124.7 10128.9 10132.8 10137.7 10146.0 5374.9 5381.2 5401.1 5432.0 5446.1 4391.9 4549.6 4926.4 4935.7 4937.7 3394.8 4037.7 4094.6 4100.1 4112.9 1336.4 1360.7 1361.4 1370.4 1374.6 783.2 851.3 904.4 908.3 968.8 4932.4 5777.6 5900.9 6031.3 6041.2 962.4 974.5 992.5 1001.6 1005.6 3770.1 3853.2 3857.8 3858.6 3958.6 9132.9 10242.3 10591.3 10681.1 10736.0 10696.1 10719.7 10720.9 10740.8 10773.8 7754.5 8230.6 8348.0 8477.8 8503.3 5645.9 5712.8 5748.8 5820.5 5991.8 9247.7 9315.3 9440.5 9613.5 9622.1 9590.9 9594.4 9799.7 9857.5 9891.7 6666.7 6839.2 7374.0 7417.4 7486.7 8118.3 8211.6 8298.0 8304.3 8328.0 7142.3 7259.0 7303.0 7303.5 7420.6 7100.3 7160.5 7212.7 7255.3 7301.5 6746.2 6859.0 6891.6 6965.1 6980.0 4729.8 4807.6 4823.2 4916.9 4974.7 4855.4 4887.1 4898.9 4948.8 5095.7 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5882.07 Median:6718.68 stddev:1865.44 (27.8%) stddev-sans-first:1823.41 18:24:24 INFO - PROCESS | 1660 | Values: 322.7 327.0 332.6 338.9 339.3 6668.2 6687.0 6720.5 6784.1 6791.6 6672.1 6683.5 6708.3 6716.8 6730.3 4686.4 4787.8 4829.1 4859.4 4923.4 4900.5 4904.0 4943.6 4944.0 4948.7 4929.6 4943.9 4951.6 4955.8 4959.0 4693.5 4788.2 4885.2 4905.8 4922.3 4186.8 4188.2 4190.2 4193.2 4197.5 5759.8 5835.8 5868.0 6319.6 6422.4 5889.8 5900.4 5903.6 5912.8 5924.8 6892.9 7134.2 7146.1 7155.6 7161.0 4766.0 4777.3 4785.6 4800.8 4814.7 6239.5 7567.3 7637.4 7641.7 7650.4 4735.5 4834.3 4851.8 4861.5 4861.8 4919.0 4930.7 4931.4 4937.4 4944.7 5663.4 5868.9 5891.3 5895.4 5905.3 7197.3 7200.5 7203.9 7210.5 7238.8 1662.6 1703.3 1704.7 1709.2 1710.0 1034.5 1047.3 1093.9 1102.4 1122.9 6604.2 6724.4 6724.8 6734.2 6758.4 6906.4 7552.0 7566.0 7567.2 7582.7 6775.3 6784.1 6795.5 6811.0 6820.4 3774.8 3940.2 4001.3 4004.1 4008.1 6519.8 7050.4 7298.3 7840.5 7915.6 7762.8 7768.8 7772.6 7773.2 7776.2 6423.9 7872.2 7882.2 7886.1 7901.1 7181.9 7446.0 7503.4 7524.2 7528.9 7224.8 7280.3 7296.7 7300.0 7342.3 7864.0 7876.3 7888.1 7896.2 7903.9 4660.9 4690.2 4726.8 4731.2 4732.6 7176.4 7465.7 7481.6 7497.6 7524.6 7108.4 7112.4 7143.1 7153.5 7160.7 7145.5 7150.5 7159.6 7167.9 7190.5 7020.0 7022.3 7026.2 7040.7 7052.3 6808.0 7107.1 7114.6 7125.4 7149.8 6647.4 6724.4 6747.3 6858.9 6890.1 18:24:24 INFO - PROCESS | 1660 | 18:24:24 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:17999.92 Median:625.20 stddev:25076.49 (4011.0%) stddev-sans-first:25113.05 18:24:24 INFO - PROCESS | 1660 | Values: 664.1 672.2 683.1 721.4 734.0 15041.1 15290.5 17186.3 17493.7 17612.8 13432.8 15944.7 16036.2 16042.9 16070.7 749.4 759.7 902.3 943.3 951.2 911.5 914.6 914.8 916.6 916.6 972.8 972.9 973.0 973.6 982.0 779.7 787.4 793.6 966.3 979.5 580.0 714.5 715.2 717.7 717.9 15109.2 15154.2 15155.9 15191.5 15224.5 12239.1 12295.0 12339.7 12412.9 12571.7 9934.9 10015.0 10114.8 10408.1 10831.1 3498.5 3517.2 3540.5 3545.9 3591.8 83410.6 83421.3 84636.6 84794.0 84814.6 9395.9 9493.7 9546.5 9667.9 9722.8 17279.0 20821.8 21074.0 21278.6 21344.3 6835.9 6927.1 7026.8 7139.6 7246.7 4010.7 4058.7 4075.6 4089.7 4124.7 3576.1 3629.9 3643.2 3643.2 3654.7 629.3 636.4 645.9 648.0 649.0 538.6 541.0 556.7 600.4 621.1 7767.7 7979.8 8044.8 8148.0 8180.2 533.6 551.1 596.0 613.1 613.6 2954.9 3621.4 3648.6 3808.5 3920.5 87756.0 89236.0 90204.6 90831.1 90904.5 71440.3 71718.6 73885.5 75142.2 75749.6 66470.7 66913.3 66973.3 68425.0 69218.0 8242.1 8299.5 8400.5 8401.5 8711.7 44570.6 47044.5 47824.5 47924.0 48776.0 63298.4 64305.4 64412.4 65621.3 66913.7 10404.5 10530.6 10692.4 10732.0 11265.6 11844.1 11863.7 11890.8 12060.3 12137.1 11055.8 11126.5 11166.4 11371.4 11539.4 11023.8 11194.4 11218.2 11263.3 11335.0 10775.1 10917.2 11252.4 11277.8 11907.3 9505.9 9546.7 9884.6 9955.6 10103.5 9522.5 9580.4 9648.8 9661.1 9860.3 18:24:24 INFO - PROCESS | 1660 | -------- Summary: end -------- 18:24:24 INFO - PROCESS | 1660 | 18:24:25 INFO - TEST-INFO | 1660: exit 0 18:24:25 INFO - TEST-OK | dromaeo_css | took 1115482ms 18:24:25 INFO - TEST-START | kraken 18:24:25 INFO - operating with platform_type : mac_ 18:24:25 INFO - Initialising browser for kraken test... 18:24:25 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpuspK4i/profile http://localhost:49276/getInfo.html) 18:24:27 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 18:24:27 INFO - PROCESS | 1670 | colorDepth:24 18:24:27 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 18:24:27 INFO - PROCESS | 1670 | __metrics 18:24:27 INFO - PROCESS | 1670 | 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" 18:24:27 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:24:28 INFO - TEST-INFO | 1670: exit 0 18:24:28 INFO - Browser initialized. 18:24:28 INFO - Running cycle 1/1 for kraken test... 18:24:28 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/tmpuspK4i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:40 INFO - PROCESS | 1672 | RSS: Main: 211341312 18:24:40 INFO - PROCESS | 1672 | 18:24:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:44 INFO - PROCESS | 1672 | RSS: Main: 272543744 18:24:44 INFO - PROCESS | 1672 | 18:24:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 18:24:47 INFO - PROCESS | 1672 | RSS: Main: 285933568 18:24:47 INFO - PROCESS | 1672 | 18:24:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 18:24:51 INFO - PROCESS | 1672 | RSS: Main: 372264960 18:24:51 INFO - PROCESS | 1672 | 18:24:53 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 18:24:53 INFO - PROCESS | 1672 | RSS: Main: 279580672 18:24:53 INFO - PROCESS | 1672 | 18:24:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:24:56 INFO - PROCESS | 1672 | RSS: Main: 280272896 18:24:56 INFO - PROCESS | 1672 | 18:24:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 18:24:59 INFO - PROCESS | 1672 | RSS: Main: 319197184 18:24:59 INFO - PROCESS | 1672 | 18:25:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 18:25:03 INFO - PROCESS | 1672 | RSS: Main: 318545920 18:25:03 INFO - PROCESS | 1672 | 18:25:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 18:25:06 INFO - PROCESS | 1672 | RSS: Main: 318095360 18:25:06 INFO - PROCESS | 1672 | 18:25:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:09 INFO - PROCESS | 1672 | RSS: Main: 309350400 18:25:09 INFO - PROCESS | 1672 | 18:25:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:25:11 INFO - PROCESS | 1672 | RSS: Main: 316116992 18:25:11 INFO - PROCESS | 1672 | 18:25:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:14 INFO - PROCESS | 1672 | RSS: Main: 318726144 18:25:14 INFO - PROCESS | 1672 | 18:25:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:17 INFO - PROCESS | 1672 | RSS: Main: 320733184 18:25:17 INFO - PROCESS | 1672 | 18:25:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:21 INFO - PROCESS | 1672 | RSS: Main: 317444096 18:25:21 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 18:25:23 INFO - PROCESS | 1672 | RSS: Main: 381861888 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | __start_tp_report 18:25:23 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 18:25:23 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 18:25:23 INFO - PROCESS | 1672 | |i|pagename|runs| 18:25:23 INFO - PROCESS | 1672 | |0;ai-astar;114;112;117;112;112;113;116;172;168;133 18:25:23 INFO - PROCESS | 1672 | |1;audio-beat-detection;156;122;142;164;122;140;139;119;167;117 18:25:23 INFO - PROCESS | 1672 | |2;audio-dft;186;184;186;184;189;185;185;185;184;184 18:25:23 INFO - PROCESS | 1672 | |3;audio-fft;73;74;74;149;59;64;82;94;148;55 18:25:23 INFO - PROCESS | 1672 | |4;audio-oscillator;96;95;143;77;80;83;126;79;77;125 18:25:23 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;93;122;113;86;87;84;89;86;87;85 18:25:23 INFO - PROCESS | 1672 | |6;imaging-darkroom;139;146;110;94;93;100;94;94;93;92 18:25:23 INFO - PROCESS | 1672 | |7;imaging-desaturate;89;87;88;86;89;90;86;89;86;105 18:25:23 INFO - PROCESS | 1672 | |8;json-parse-financial;60;64;66;64;63;67;64;84;58;57 18:25:23 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;63;65;72;66;79;78;74;68;70;69 18:25:23 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;81;81;80;81;80;81;80;81;81;83 18:25:23 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;129;131;126;147;147;121;143;136;173;152 18:25:23 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;174;165;164;162;173;159;172;174;163;162 18:25:23 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;68;72;73;96;94;81;68;67;69;80 18:25:23 INFO - PROCESS | 1672 | __end_tp_report 18:25:23 INFO - PROCESS | 1672 | __start_cc_report 18:25:23 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2074 18:25:23 INFO - PROCESS | 1672 | __end_cc_report 18:25:23 INFO - PROCESS | 1672 | __startTimestamp1462584323486__endTimestamp 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | ------- Summary: start ------- 18:25:23 INFO - PROCESS | 1672 | Number of tests: 14 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:126.90 Median:116.50 stddev:23.58 (20.2%) stddev-sans-first:24.54 18:25:23 INFO - PROCESS | 1672 | Values: 114.0 112.0 117.0 112.0 112.0 113.0 116.0 172.0 168.0 133.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:141.00 stddev:18.77 (13.3%) stddev-sans-first:18.84 18:25:23 INFO - PROCESS | 1672 | Values: 156.0 122.0 142.0 164.0 122.0 140.0 139.0 119.0 167.0 117.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:185.20 Median:185.00 stddev:1.55 (0.8%) stddev-sans-first:1.62 18:25:23 INFO - PROCESS | 1672 | Values: 186.0 184.0 186.0 184.0 189.0 185.0 185.0 185.0 184.0 184.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:87.20 Median:73.50 stddev:34.17 (46.5%) stddev-sans-first:35.86 18:25:23 INFO - PROCESS | 1672 | Values: 73.0 74.0 74.0 149.0 59.0 64.0 82.0 94.0 148.0 55.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:79.50 stddev:24.37 (30.6%) stddev-sans-first:25.83 18:25:23 INFO - PROCESS | 1672 | Values: 96.0 95.0 143.0 77.0 80.0 83.0 126.0 79.0 77.0 125.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:86.50 stddev:13.21 (15.3%) stddev-sans-first:14.02 18:25:23 INFO - PROCESS | 1672 | Values: 93.0 122.0 113.0 86.0 87.0 84.0 89.0 86.0 87.0 85.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:105.50 Median:93.00 stddev:20.29 (21.8%) stddev-sans-first:17.53 18:25:23 INFO - PROCESS | 1672 | Values: 139.0 146.0 110.0 94.0 93.0 100.0 94.0 94.0 93.0 92.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:89.50 Median:88.50 stddev:5.64 (6.4%) stddev-sans-first:5.98 18:25:23 INFO - PROCESS | 1672 | Values: 89.0 87.0 88.0 86.0 89.0 90.0 86.0 89.0 86.0 105.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.00 stddev:7.53 (11.8%) stddev-sans-first:7.79 18:25:23 INFO - PROCESS | 1672 | Values: 60.0 64.0 66.0 64.0 63.0 67.0 64.0 84.0 58.0 57.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:5.36 (7.5%) stddev-sans-first:4.97 18:25:23 INFO - PROCESS | 1672 | Values: 63.0 65.0 72.0 66.0 79.0 78.0 74.0 68.0 70.0 69.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:0.88 (1.1%) stddev-sans-first:0.93 18:25:23 INFO - PROCESS | 1672 | Values: 81.0 81.0 80.0 81.0 80.0 81.0 80.0 81.0 81.0 83.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:145.00 stddev:15.32 (10.6%) stddev-sans-first:15.67 18:25:23 INFO - PROCESS | 1672 | Values: 129.0 131.0 126.0 147.0 147.0 121.0 143.0 136.0 173.0 152.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:168.50 stddev:5.79 (3.4%) stddev-sans-first:5.52 18:25:23 INFO - PROCESS | 1672 | Values: 174.0 165.0 164.0 162.0 173.0 159.0 172.0 174.0 163.0 162.0 18:25:23 INFO - PROCESS | 1672 | 18:25:23 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:76.50 stddev:10.76 (14.1%) stddev-sans-first:10.93 18:25:23 INFO - PROCESS | 1672 | Values: 68.0 72.0 73.0 96.0 94.0 81.0 68.0 67.0 69.0 80.0 18:25:23 INFO - PROCESS | 1672 | -------- Summary: end -------- 18:25:23 INFO - PROCESS | 1672 | 18:25:24 INFO - TEST-INFO | 1672: exit 0 18:25:24 INFO - TEST-OK | kraken | took 59205ms 18:25:24 INFO - SUITE-END | took 1174s 18:25:24 INFO - Completed test suite (00:19:35) 18:25:24 INFO - javascript benchmark 18:25:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3203.4393981053317, 3460.0, 3485.1461391958965, 3485.3900567400697, 3527.7354198435123, 5681.545476361892, 5682.744276507557, 5702.74337654806, 5767.884642307162, 5771.336296325924, 5816.738246778895, 5838.649681019142, 5842.795502157425, 5860.033094539396, 5875.823725288237, 5168.819091331804, 5219.608529360295, 5256.8160114396005, 5264.684118952856, 5370.785168593271, 5316.175992721115, 5317.973410132963, 5318.202269659562, 5321.148616221405, 5333.226682131089, 5247.632665713401, 5289.127293996506, 5303.310569625944, 5306.363236411636, 5310.70791106489, 4437.622802061813, 5343.759530821122, 5352.785888564453, 5357.142857142839, 5399.487048730364, 5109.6167787415725, 5138.665986710889, 5144.254083157958, 5235.65968212065, 5240.161574148118, 5619.438056194389, 5654.575906807006, 5658.688772117532, 5662.462066103713, 5756.395578464285, 6146.815595532141, 6155.846103847395, 6180.783672571439, 6185.41238582334, 6191.690415479253, 5317.361916569997, 6121.66330851803, 6172.598781079216, 6187.5668703190795, 6191.102290167901, 5886.735096920649, 5894.0, 5910.202122713422, 5931.991561434566, 5944.346121926628, 6298.590591611531, 6317.684115794192, 6322.399372059647, 6326.304106548276, 6335.303116657164, 5973.074173503114, 6064.792498024651, 6093.786717464867, 6184.5980011299125, 6195.31851496335, 6140.13616995768, 6240.31356550779, 6241.469475094577, 6243.531735119885, 6244.594101383395, 6120.296165940974, 6163.96918015407, 6214.8135555933395, 6215.875682486325, 6222.253329600387, 6134.693265336805, 6151.169592105009, 6157.907631385533, 6179.721912513947, 6192.442680158717, 4908.828191013297, 4940.925886111711, 4951.331570237971, 4951.925721114186, 4956.677815941952, 3811.9047023824073, 4460.665450091256, 4483.439570053743, 4504.684672072924, 4513.5486451355255, 5774.94225057744, 5790.478856902899, 5884.293884733859, 5911.438413350725, 5919.3192782829665, 6105.145279660851, 6157.538184636171, 6183.072539119078, 6209.534284928558, 6215.627062376272, 4532.4334458192725, 5771.711414429262, 5821.330546987151, 5825.970870145622, 5848.707564621752, 5312.893742125136, 5390.838274851761, 5408.242846001563, 5452.781888724407, 5477.972610137003, 6352.047192921099, 6362.45919096882, 6401.1678481798, 6410.64741439216, 6427.710753016126, 5563.9224808513, 6160.199174107337, 6192.318844926965, 6193.102000210034, 6195.628262304276, 6272.121902933684, 6340.334264902119, 6373.904391434225, 6428.260750013808, 6438.967805161132, 6158.3225845157895, 6162.106494558354, 6180.845478863064, 6183.81448556544, 6188.0717892314715, 5993.880122397615, 6105.5420843437805, 6155.538334624832, 6171.876562468815, 6184.5052395809125, 6036.396360363929, 6074.939250607437, 6075.696215189135, 6075.817725468243, 6093.969530152321, 6018.187544681412, 6089.299730530868, 6090.939090609215, 6152.323244443197, 6154.1999540059505, 5991.610545314541, 5999.730012149376, 5999.880002400015, 6017.308009578955, 6023.939760602513, 6084.726187321493, 6103.572749907641, 6105.297890742621, 6114.847128821815, 6125.356837532173, 5778.913316300343, 5841.766329346949, 5906.940930590639, 5915.053591425351, 5979.730912109053], "value": 5704.614950880139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.3745755263474, 541.4720647368816, 561.0630247486697, 573.5841514901699, 601.9796445025684, 31965.52051719226, 32422.0, 32558.83720581397, 39644.60355396445, 40567.797161014154, 45874.311885321746, 46032.76983615077, 46321.305180422234, 46373.7681311593, 46431.0, 9657.565409556586, 9881.703548893545, 9890.30767846251, 9908.256880733938, 9925.652602158925, 8407.495550266973, 8695.95652021739, 11739.413029348498, 12018.579349722762, 12026.579069732561, 9906.405615663047, 11821.586244481445, 11930.463129159165, 11931.0, 11937.641870743892, 8314.251717345467, 11834.408279586029, 11896.702582435422, 11903.047756179485, 11916.28502289861, 11739.236949598335, 11971.521139154424, 11980.880191198064, 11988.220765650205, 11993.220440671328, 39842.40236396456, 40425.595744042774, 40493.0, 40499.0, 40502.594974050175, 3224.3242703318238, 3294.6540613235506, 3370.3933292007428, 3491.825408729553, 3571.053670777246, 28000.439991200266, 28141.0, 28736.994205202718, 28906.71093289061, 28948.0, 2784.763295119917, 2804.42509285598, 2811.845348505831, 2846.0, 2888.422315536901, 485435.0, 487247.0, 487838.0, 488482.0, 488528.99999999645, 36429.0, 37051.62948370536, 37242.25515489675, 37243.4413483798, 67633.0, 35859.64140358563, 36075.63924360723, 36195.819020904724, 36343.09142271464, 36359.90920272396, 15646.9217653911, 15796.684066318609, 15840.44558440449, 15853.445129420415, 15860.127692976997, 12386.442610082568, 12750.61748147557, 13367.398467069006, 13468.057235993387, 13490.595282141356, 8289.336853051742, 8416.579171041423, 8432.325413966868, 8471.449355791854, 8539.316854651614, 3411.6417776133644, 3442.896713098611, 3447.3967055765143, 3462.515247865301, 3478.5477887874413, 893.7944272817289, 1015.9339642923334, 1045.5347370420243, 1071.405370019631, 1103.4041617526607, 30728.0, 31018.0, 31172.0, 31406.0, 31898.2025449361, 1094.499069625901, 1132.6149109302876, 1145.604766355606, 1188.197966372695, 1208.238809549978, 15064.698706026038, 15243.771343429857, 15448.922755386151, 15515.0, 15630.452934147477, 579495.0, 583809.0, 586797.3300667432, 586926.0, 587535.0, 62872.68563657244, 65030.67484662641, 65083.34916650868, 65099.0, 65374.67312663501, 1337.317967836391, 1345.7510360583324, 1517.0973270903794, 1519.6352875309624, 1527.7861099446086, 29052.56421153642, 29499.410011799213, 29807.701922980494, 30403.2399190022, 30440.695593043787, 55473.0, 56286.0, 56389.43610564006, 56535.151972721265, 56633.433665662815, 519412.4029379829, 519873.0, 520938.0, 522656.0, 524070.0, 24384.756152438247, 27670.44659106847, 28071.438571228053, 28306.2923426916, 28366.574501382907, 43077.87577031701, 44433.77783111064, 44517.887052823935, 44646.77676611596, 45335.31997019982, 35636.64363356333, 35955.640443596276, 36456.453153202194, 37214.44178337381, 37303.253934920605, 36724.0818979517, 36926.261474770894, 37033.259334813694, 37640.43539346857, 37770.0, 34831.0, 38303.808480957414, 39422.0, 39863.00342491461, 40716.79641601773, 27393.452130957667, 28317.0, 30366.696333036387, 32075.67924320727, 32469.0, 20890.373288801362, 25289.494210116063, 25363.746362536876, 25722.871385642953, 25931.481370372865], "value": 18933.23112779736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.922494726002, 410.8110269276133, 415.4764996104907, 419.2474508257677, 420.6697742272317, 13906.0, 19886.801131988675, 20566.971651417425, 20649.896750516244, 20710.896445517752, 15972.600684982881, 16462.094584797833, 20156.496087597818, 20637.86491742957, 20696.586068278662, 5600.52395546378, 5685.744141513642, 5865.061590145578, 5880.441358070976, 5890.941090589104, 4124.484439445069, 5750.971245143768, 5816.243888294516, 5842.0, 5847.532197424217, 6179.104029915659, 6486.2540807807, 6489.870202595946, 6492.772752953648, 6496.870062598745, 6347.904781428282, 6405.071264666643, 6429.839254018617, 6429.967850160766, 6436.195475565554, 5497.120460726304, 5608.9439105609235, 5643.68959707216, 5656.490915817596, 5656.519195868356, 23966.52066958651, 24404.755952440424, 24728.629070563955, 24852.3786905327, 25408.237752867444, 1981.4352909420745, 2228.353777404551, 2354.2348736660483, 2402.699662542182, 2418.37122348189, 9484.952575237148, 10592.735181620445, 14557.417703291856, 15162.924185379003, 15250.618734531725, 1711.3582406597525, 1756.7364895265684, 1765.2674140231788, 1784.2595322940963, 1800.288885890066, 112275.87724122735, 114966.27550586748, 118479.0, 121049.78950210473, 125942.0, 10793.974572415607, 11472.713182170513, 11515.25150865191, 11533.596324128615, 11589.536418543164, 13182.538611148564, 15068.095914245181, 15174.544763657108, 15179.316930738143, 15201.6199595011, 7897.644605992629, 8250.669973201006, 8252.0, 8268.338532917352, 8730.563471826383, 7787.844243115106, 8150.592470376458, 8189.754307370788, 8695.869561956575, 8878.378513504122, 4629.305604159403, 4662.090892275974, 4676.965519998744, 4694.43666759991, 4717.561143851159, 1946.0756140832991, 1985.414302780683, 2000.57987822556, 2006.3178519303508, 2009.7286866272866, 729.6607077708891, 865.2904618213005, 867.2714919467677, 897.3852910756153, 1021.2085633633993, 18923.716144257847, 19295.710564341545, 19464.221431142596, 19540.316088937103, 19568.70646940297, 853.7865533616596, 916.867054277126, 979.7060881735451, 992.538469611634, 1002.1281485107856, 8678.522681252593, 10423.374597524173, 10459.895401046044, 10471.94764026175, 10536.051755342056, 658735.0, 660074.0, 663961.0, 678908.0, 681975.0, 131377.34311328572, 136299.31850340884, 137041.0, 137168.0, 142721.0, 895.1943251074085, 1048.1876545677162, 1063.6330465989222, 1069.7218723131887, 1086.9782604348027, 17407.390741323994, 17448.47654570365, 17681.823181768017, 17684.027378494306, 17697.734533982257, 48278.0, 50457.0, 51685.48314516957, 53690.0, 54801.17798233109, 68924.0, 73275.26724732685, 73558.0, 75671.86492202732, 77288.61355693186, 7914.445988780961, 8229.177082291722, 8293.751187464386, 8359.205875441823, 8569.742907712778, 9251.907480925374, 11958.402079895797, 12860.742785144057, 13017.609471715863, 13278.535251266545, 10884.61903833362, 10949.890501094887, 11159.832602510807, 11262.7747445049, 11440.027597654293, 11057.447127643425, 11184.496697648789, 11283.66149015531, 11427.200095993534, 11621.47703353368, 11190.94404527972, 11351.205415620827, 11484.540618375171, 11607.477663505331, 11747.118966077749, 8330.333573314, 8360.66557337724, 8400.495970241805, 8423.368247381348, 8568.314534837322, 10587.4706264688, 11039.39283339424, 11246.381449020382, 11405.600803971822, 11569.074474042225], "value": 9627.111565897658, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.8799508044274, 1397.3781667158116, 1621.5054408405435, 1846.6675998320297, 2032.0449388787274, 7939.801504962379, 8716.041235464101, 8844.911550884497, 8868.512231827246, 8877.422967507107, 8534.615942282597, 8806.339524535653, 8813.559322033905, 8833.116688331163, 8836.91163088369, 2157.687135365371, 2215.7784221577795, 2224.154821167962, 2392.9999999999955, 2423.006567307404, 2202.493426511903, 2245.8989345479413, 2448.5837407640743, 2515.610080437535, 2516.6099254615565, 2102.6740855167473, 2410.5901996660523, 2463.5319289335057, 2465.174166654164, 2468.5062987402503, 2139.70044193813, 2417.1177520205174, 2421.806255499556, 2425.199684104259, 2425.871969534175, 2401.147592604618, 2468.8642124683133, 2470.3947532854568, 2470.938226544333, 2474.5894839941243, 8646.308295336357, 8726.476411415335, 8734.432261903019, 8737.344699147527, 8742.344324175712, 5198.298229739013, 5371.194320851864, 5386.0, 5413.729313534308, 5423.891522169534, 3429.554157959449, 3518.7712798668003, 3601.7838929664304, 3615.529981102466, 3625.782453052825, 1042.6402891002592, 1052.147760314148, 1054.8575942247776, 1074.8280275155937, 1098.549594666191, 12061.155719099666, 12092.214006089576, 12094.516219351215, 12110.939445302716, 12112.757744845141, 4758.3606232013635, 4771.355866957986, 4790.952090479121, 4850.975745121252, 4902.975485122552, 10124.74688132788, 10128.898711012796, 10132.797344053077, 10137.69586912379, 10146.0, 5374.946250537445, 5381.165919282517, 5401.054815407288, 5432.02223599748, 5446.128619420873, 4391.912161756747, 4549.567791059844, 4926.3842019747535, 4935.703857768545, 4937.679050861754, 3394.7963122212745, 4037.6971727120585, 4094.61101195386, 4100.097978444737, 4112.897177570585, 1336.3719052045524, 1360.6734383748028, 1361.360160724458, 1370.383327502608, 1374.6494643865951, 783.2011348424575, 851.3061854588615, 904.3759805734022, 908.2824568590871, 968.7771812483028, 4932.358793356841, 5777.62445441045, 5900.911486327708, 6031.306399764083, 6041.154238406627, 962.4417837654144, 974.5224839828433, 992.5186284651938, 1001.5743309093606, 1005.607812952949, 3770.057485628593, 3853.1715681128694, 3857.8456861725217, 3858.6141385861188, 3958.6041395860184, 9132.908670913339, 10242.334248273839, 10591.258611897094, 10681.092107170978, 10736.0, 10696.144308455305, 10719.732006699895, 10720.946395267974, 10740.838887416696, 10773.83839242412, 7754.534727916231, 8230.588470576567, 8348.0, 8477.830443391098, 8503.319734421231, 5645.887082258414, 5712.80005199816, 5748.770049198152, 5820.505257053198, 5991.7603295867675, 9247.72256832296, 9315.301352398461, 9440.527973601154, 9613.471259080818, 9622.13400793932, 9590.856137158087, 9594.376365536218, 9799.706008819747, 9857.507124643882, 9891.653792117242, 6666.699998499982, 6839.213490448662, 7373.963130184315, 7417.443691723034, 7486.7005319786595, 8118.269355757774, 8211.58942052907, 8297.95851020741, 8304.29413499835, 8328.042275138436, 7142.250063743232, 7258.98374227598, 7303.014093097577, 7303.452241081834, 7420.6289685516585, 7100.325469080429, 7160.462965277521, 7212.675429605575, 7255.274472552703, 7301.452391070585, 6746.212253752661, 6858.965705171642, 6891.552049116792, 6965.129358830146, 6979.987901754115, 4729.763511824326, 4807.591354734747, 4823.2282834746275, 4916.8770780730765, 4974.726390048582, 4855.393075865517, 4887.144749668722, 4898.902021959612, 4948.826791062295, 5095.668781529189], "value": 4586.680621810668, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.6579825385092, 326.95749552558163, 332.6307798343839, 338.8542926541586, 339.2553345406831, 6668.199816022078, 6687.030380594817, 6720.529562930591, 6784.084148639932, 6791.626460544668, 6672.065910772496, 6683.498737594676, 6708.329167083301, 6716.83207919801, 6730.293319201487, 4686.41419822523, 4787.784549695272, 4829.1307564638355, 4859.416869975608, 4923.433805112404, 4900.534449227318, 4904.0191961607825, 4943.604511639078, 4943.9752801235945, 4948.678335908155, 4929.605631549485, 4943.876403089916, 4951.628627852908, 4955.801767929279, 4958.950410495903, 4693.530646935296, 4788.186008378567, 4885.218365061609, 4905.754712264373, 4922.261660750916, 4186.769727664977, 4188.225178341986, 4190.245755763961, 4193.182329445759, 4197.517285512175, 5759.798407055733, 5835.824925252206, 5868.002439585281, 6319.620822750604, 6422.421982021594, 5889.823305300848, 5900.350961394243, 5903.616264942765, 5912.793052243193, 5924.792632257893, 6892.896606550906, 7134.17956934954, 7146.106736657918, 7155.642217889084, 7161.0, 4765.976170119127, 4777.331173635694, 4785.64107691921, 4800.831970881038, 4814.7351895645725, 6239.469645080174, 7567.31894129531, 7637.427192960495, 7641.656125474367, 7650.42621803367, 4735.502772208937, 4834.347363105936, 4851.830185943475, 4861.51384861511, 4861.829835955796, 4919.0407870464915, 4930.704157750475, 4931.3589233399425, 4937.407511098691, 4944.653874228769, 5663.405342439067, 5868.911966320508, 5891.3224979127945, 5895.35151133375, 5905.261842269716, 7197.280271972761, 7200.531965422231, 7203.89194162088, 7210.495265331377, 7238.819029524304, 1662.6009757658076, 1703.3186725309727, 1704.7442883567317, 1709.2223038517445, 1709.991450042742, 1034.462079718542, 1047.3349423116376, 1093.9398333091597, 1102.4267380961855, 1122.9157813164045, 6604.2074951005225, 6724.3948044675235, 6724.831879203059, 6734.225564060098, 6758.425533829582, 6906.447484201251, 7551.962240188764, 7566.0, 7567.243275672498, 7582.658780354787, 6775.3224677532835, 6784.118064651564, 6795.456363491007, 6811.012403201508, 6820.3861652451515, 3774.8490060397826, 3940.192260586573, 4001.2797696414923, 4004.0790618157985, 4008.0581063450068, 6519.8044058678315, 7050.435965122778, 7298.343149116605, 7840.529568225925, 7915.604219788987, 7762.7671169865, 7768.844623107506, 7772.611369431505, 7773.183815699384, 7776.222377762291, 6423.903641445476, 7872.173421790744, 7882.172371900868, 7886.05367355898, 7901.091374492007, 7181.928180718126, 7445.9627701861145, 7503.4372422069655, 7524.247575242651, 7528.924710752823, 7224.819379515554, 7280.271972802678, 7296.671649775879, 7299.963500182465, 7342.302481264272, 7864.0, 7876.330511906552, 7888.132305446282, 7896.210378962058, 7903.881441778263, 4660.86017419478, 4690.179218636793, 4726.787294571684, 4731.172044892061, 4732.574068333866, 7176.390006849476, 7465.664045118091, 7481.588512631857, 7497.550146991198, 7524.5861477619255, 7108.395786358011, 7112.431005519443, 7143.071400717873, 7153.463490238149, 7160.713571457083, 7145.4640901931525, 7150.5352152109945, 7159.60622165765, 7167.928320716935, 7190.460715446257, 7020.017197592239, 7022.332878376546, 7026.19198792125, 7040.718371265092, 7052.259512751292, 6807.965960170168, 7107.076080109553, 7114.6442677866935, 7125.429965602843, 7149.821254468472, 6647.434968027578, 6724.428423583854, 6747.257801641914, 6858.862822743617, 6890.10428644273], "value": 5255.107408598232, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.1266734244471, 672.247083266741, 683.0948992584825, 721.4084450750383, 734.0200831889427, 15041.097534147944, 15290.541283761491, 17186.312547498103, 17493.650126997483, 17612.82387176128, 13432.798508022388, 15944.681106377895, 16036.198190090507, 16042.876998610102, 16070.678586428265, 749.4379215588309, 759.7302957450108, 902.2511315608047, 943.3302355327726, 951.2485136741966, 911.5487833522383, 914.6341463414655, 914.7713071732067, 916.5508900638672, 916.6287653500343, 972.8200282947641, 972.8735264415616, 972.9783727086531, 973.5667627905585, 981.9705408837747, 779.6764342797732, 787.4173111897155, 793.5635400529743, 966.2656381150306, 979.4612962870428, 579.9879210777178, 714.4998501049234, 715.1525442350779, 717.666285177395, 717.9425645948364, 15109.168995705231, 15154.242287885561, 15155.924220378938, 15191.544253672408, 15224.543263702106, 12239.082068844784, 12295.0, 12339.691507712289, 12412.937935310265, 12571.685707857285, 9934.850977235346, 10015.0, 10114.848277275847, 10408.115310198642, 10831.079358254558, 3498.5276987606603, 3517.191046059421, 3540.539729835131, 3545.8936231912826, 3591.838367273453, 83410.58294708548, 83421.32629389696, 84636.57681711613, 84794.0, 84814.57592712059, 9395.90604093964, 9493.715188544355, 9546.522673866419, 9667.903320966841, 9722.756931076638, 17279.0, 20821.791782082288, 21074.0, 21278.574428511343, 21344.252951146947, 6835.897461538081, 6927.099477067949, 7026.824329391806, 7139.607321597258, 7246.673899674527, 4010.6590939770444, 4058.6753059755147, 4075.59244075596, 4089.7137200395687, 4124.670026397881, 3576.1238496567985, 3629.9291708945925, 3643.1620727232885, 3643.216708407705, 3654.7258955578436, 629.2826178156905, 636.38270877249, 645.9257185423736, 647.970841312142, 648.9837754056186, 538.6139402447119, 541.0172312997261, 556.7355506134554, 600.3636145685588, 621.1118101115358, 7767.689292428239, 7979.84040319202, 8044.839103217902, 8148.022237331558, 8180.222878826502, 533.6024661627035, 551.1209620655048, 596.0135974961446, 613.1324176290589, 613.6318209074519, 2954.9113526594238, 3621.402468592653, 3648.635136486383, 3808.485854409619, 3920.5491368492417, 87756.0, 89236.0, 90204.6469302961, 90831.09168908227, 90904.54547727351, 71440.28559714441, 71718.64140679264, 73885.52228955498, 75142.24857751353, 75749.6212518945, 66470.66764666242, 66913.33086669169, 66973.33026669768, 68425.0, 69218.0, 8242.134575869448, 8299.543525106177, 8400.453970492143, 8401.495910245405, 8711.651533938571, 44570.55429445664, 47044.52955470538, 47824.52175478201, 47924.0, 48776.0, 63298.36701632925, 64305.356946431806, 64412.35587644063, 65621.34378656153, 66913.66543167253, 10404.531796069045, 10530.631427899987, 10692.411917344622, 10732.0, 11265.57790075105, 11844.111691622991, 11863.703407414883, 11890.821637675615, 12060.336681482602, 12137.089718270989, 11055.778884422427, 11126.49930753102, 11166.44167791591, 11371.43142842838, 11539.36533490666, 11023.779524409627, 11194.384308863091, 11218.214724969173, 11263.324200547993, 11335.0, 10775.137988960707, 10917.23579349438, 11252.399232245603, 11277.774444511228, 11907.285562866256, 9505.906820715707, 9546.665866694633, 9884.555195016384, 9955.601775929172, 10103.54534045955, 9522.52387380642, 9580.377275477073, 9648.807023859625, 9661.13049825519, 9860.260480464138], "value": 6840.690879288266, "unit": "score"}], "name": "dromaeo_css", "value": 7453.502829204885, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 117.0, 112.0, 112.0, 113.0, 116.0, 172.0, 168.0, 133.0], "value": 126.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 142.0, 164.0, 122.0, 140.0, 139.0, 119.0, 167.0, 117.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 186.0, 184.0, 189.0, 185.0, 185.0, 185.0, 184.0, 184.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 149.0, 59.0, 64.0, 82.0, 94.0, 148.0, 55.0], "value": 87.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 143.0, 77.0, 80.0, 83.0, 126.0, 79.0, 77.0, 125.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 122.0, 113.0, 86.0, 87.0, 84.0, 89.0, 86.0, 87.0, 85.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 146.0, 110.0, 94.0, 93.0, 100.0, 94.0, 94.0, 93.0, 92.0], "value": 105.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 89.0, 90.0, 86.0, 89.0, 86.0, 105.0], "value": 89.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 66.0, 64.0, 63.0, 67.0, 64.0, 84.0, 58.0, 57.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 72.0, 66.0, 79.0, 78.0, 74.0, 68.0, 70.0, 69.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 81.0, 80.0, 81.0, 80.0, 81.0, 81.0, 83.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 126.0, 147.0, 147.0, 121.0, 143.0, 136.0, 173.0, 152.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 165.0, 164.0, 162.0, 173.0, 159.0, 172.0, 174.0, 163.0, 162.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 73.0, 96.0, 94.0, 81.0, 68.0, 67.0, 69.0, 80.0], "value": 76.8, "unit": "score"}], "name": "kraken", "value": 1524.5000000000002, "alertThreshold": 2.0}]} 18:25:24 INFO - Return code: 0 18:25:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:25:24 ERROR - # TBPL SUCCESS # 18:25:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:24 INFO - 2016-05-06 18:25:24.762 screenresolution[1676:10115] starting screenresolution argv=screenresolution get 18:25:24 INFO - 2016-05-06 18:25:24.764 screenresolution[1676:10115] Display 0: 1600x1200x32@60 18:25:24 INFO - 2016-05-06 18:25:24.770 screenresolution[1677:10118] starting screenresolution argv=screenresolution list 18:25:24 INFO - Available Modes on Display 0 18:25:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:25 INFO - Intel Iris: 18:25:25 INFO - Chipset Model: Intel Iris 18:25:25 INFO - Type: GPU 18:25:25 INFO - Bus: Built-In 18:25:25 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:25 INFO - Vendor: Intel (0x8086) 18:25:25 INFO - Device ID: 0x0a2e 18:25:25 INFO - Revision ID: 0x0009 18:25:25 INFO - Displays: 18:25:25 INFO - Display: 18:25:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:25 INFO - Display Serial Number: 1600x1200 60 18:25:25 INFO - Main Display: Yes 18:25:25 INFO - Mirror: Off 18:25:25 INFO - Online: Yes 18:25:25 INFO - Rotation: Supported 18:25:25 INFO - Return code: 0 18:25:25 INFO - Running post-action listener: _resource_record_post_action 18:25:25 INFO - Running post-run listener: _resource_record_post_run 18:25:26 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 38266368; Write bytes: 657117696; Read time: 397; Write time: 2290 18:25:26 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346849792; Write bytes: 348764160; Read time: 26536; Write time: 697 18:25:26 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 37307904; Write bytes: 297153536; Read time: 381; Write time: 1555 18:25:26 INFO - Running post-run listener: _upload_blobber_files 18:25:26 INFO - Blob upload gear active. 18:25:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:26 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 18:25:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:25:26 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:25:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:27 INFO - (blobuploader) - WARNING - File uploaded to blobserver but failed uploading to Amazon S3. 18:25:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:27 INFO - (blobuploader) - INFO - Done attempting. 18:25:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:25:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:25:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:28 INFO - (blobuploader) - INFO - Done attempting. 18:25:28 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:28 INFO - Return code: 0 18:25:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20a309cd49385ea5edf9547428f306f51c9eeadbe83867512b718a2addbffac30d49a05363db50e904f682c1568c231fc6023b32b86d93278b41985e8b135309", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3dc7a2ffd2652701362b4247d9e45deb9af6ceb79e4dda7baad34f70d36366ab236084612a6947d89278ef6c0db4b3f2efa617061100d1a17e10b9fc671de73a"} 18:25:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:28 INFO - Contents: 18:25:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20a309cd49385ea5edf9547428f306f51c9eeadbe83867512b718a2addbffac30d49a05363db50e904f682c1568c231fc6023b32b86d93278b41985e8b135309", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3dc7a2ffd2652701362b4247d9e45deb9af6ceb79e4dda7baad34f70d36366ab236084612a6947d89278ef6c0db4b3f2efa617061100d1a17e10b9fc671de73a"} 18:25:28 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:28 INFO - Copying logs to upload dir... 18:25:28 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.078783 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-06 18:25:28.521691) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:28.525491) ========= 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.b2amUv0IRK/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.7H8ErLcac7/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20a309cd49385ea5edf9547428f306f51c9eeadbe83867512b718a2addbffac30d49a05363db50e904f682c1568c231fc6023b32b86d93278b41985e8b135309", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3dc7a2ffd2652701362b4247d9e45deb9af6ceb79e4dda7baad34f70d36366ab236084612a6947d89278ef6c0db4b3f2efa617061100d1a17e10b9fc671de73a"} build_url:https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011453 build_url: 'https://queue.taskcluster.net/v1/task/cQRUPmJISLuW-k3W4FOreA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/20a309cd49385ea5edf9547428f306f51c9eeadbe83867512b718a2addbffac30d49a05363db50e904f682c1568c231fc6023b32b86d93278b41985e8b135309", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3dc7a2ffd2652701362b4247d9e45deb9af6ceb79e4dda7baad34f70d36366ab236084612a6947d89278ef6c0db4b3f2efa617061100d1a17e10b9fc671de73a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:28.553590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:28.553907) ========= 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.b2amUv0IRK/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.7H8ErLcac7/Listeners TMPDIR=/var/folders/s9/h01r07gs45gfv0h9php9jq_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005141 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:25:28.602013) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:25:28.602328) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:25:28.602627) ========= ========= Total master_lag: 0.15 =========