builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0088 starttime: 1462800672.88 results: success (0) buildid: 20160509040557 builduid: 5e1b192add0c487182cf725d3895dd3e revision: 043082cb7bd8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:12.881530) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:12.882020) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:12.882351) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:12.902310) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:12.902917) ========= 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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-09 06:31:12-- 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.19M=0.001s 2016-05-09 06:31:12 (9.19 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084308 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:13.006852) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:13.007276) ========= 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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.025869 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:13.059643) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 06:31:13.060498) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 043082cb7bd8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 043082cb7bd8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j9JOEV4DLw/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.f6TsSe8MQL/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-09 06:31:13,134 Setting DEBUG logging. 2016-05-09 06:31:13,134 attempt 1/10 2016-05-09 06:31:13,134 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/043082cb7bd8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 06:31:14,162 unpacking tar archive at: mozilla-central-043082cb7bd8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.273509 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 06:31:14.355388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:14.356110) ========= script_repo_revision: 043082cb7bd8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:14.357004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:14.357700) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 06:31:14.372755) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-09 06:31:14.373221) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j9JOEV4DLw/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.f6TsSe8MQL/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:31:14 INFO - MultiFileLogger online at 20160509 06:31:14 in /builds/slave/test 06:31:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:31:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:31:14 INFO - {'append_to_log': False, 06:31:14 INFO - 'base_work_dir': '/builds/slave/test', 06:31:14 INFO - 'blob_upload_branch': 'Firefox', 06:31:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:31:14 INFO - 'branch': 'Firefox', 06:31:14 INFO - 'buildbot_json_path': 'buildprops.json', 06:31:14 INFO - 'config_files': ('talos/mac_config.py',), 06:31:14 INFO - 'default_actions': ('clobber', 06:31:14 INFO - 'read-buildbot-config', 06:31:14 INFO - 'download-and-extract', 06:31:14 INFO - 'populate-webroot', 06:31:14 INFO - 'create-virtualenv', 06:31:14 INFO - 'install', 06:31:14 INFO - 'run-tests'), 06:31:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:31:14 INFO - 'download_minidump_stackwalk': True, 06:31:14 INFO - 'download_symbols': 'ondemand', 06:31:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:31:14 INFO - 'tooltool.py': '/tools/tooltool.py', 06:31:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:31:14 INFO - '/tools/misc-python/virtualenv.py')}, 06:31:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:31:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:31:14 INFO - 'installer_path': 'installer.exe', 06:31:14 INFO - 'log_level': 'info', 06:31:14 INFO - 'log_name': 'talos', 06:31:14 INFO - 'log_to_console': True, 06:31:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:31:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:31:14 INFO - 'opt_config_files': (), 06:31:14 INFO - 'pip_index': False, 06:31:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:31:14 INFO - 'cmd': ('bash', 06:31:14 INFO - '-c', 06:31:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:31:14 INFO - 'enabled': True, 06:31:14 INFO - 'halt_on_failure': False, 06:31:14 INFO - 'name': 'check_screen_resolution'},), 06:31:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:31:14 INFO - 'cmd': ('bash', 06:31:14 INFO - '-c', 06:31:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:31:14 INFO - 'enabled': True, 06:31:14 INFO - 'halt_on_failure': False, 06:31:14 INFO - 'name': 'check_screen_resolution'},), 06:31:14 INFO - 'run_cmd_checks_enabled': True, 06:31:14 INFO - 'sps_profile': False, 06:31:14 INFO - 'sps_profile_interval': 0, 06:31:14 INFO - 'suite': 'dromaeojs-e10s', 06:31:14 INFO - 'system_bits': '32', 06:31:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:31:14 INFO - 'title': 't-yosemite-r7-0088', 06:31:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:31:14 INFO - 'use_talos_json': True, 06:31:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:31:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:31:14 INFO - 'work_dir': 'build'} 06:31:14 INFO - ##### 06:31:14 INFO - ##### Running clobber step. 06:31:14 INFO - ##### 06:31:14 INFO - Running pre-action listener: _resource_record_pre_action 06:31:14 INFO - Running main action method: clobber 06:31:14 INFO - rmtree: /builds/slave/test/build 06:31:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:31:16 INFO - Running post-action listener: _resource_record_post_action 06:31:16 INFO - ##### 06:31:16 INFO - ##### Finished clobber step (success) 06:31:16 INFO - ##### 06:31:16 INFO - ##### 06:31:16 INFO - ##### Running read-buildbot-config step. 06:31:16 INFO - ##### 06:31:16 INFO - Running pre-action listener: _resource_record_pre_action 06:31:16 INFO - Running main action method: read_buildbot_config 06:31:16 INFO - Using buildbot properties: 06:31:16 INFO - { 06:31:16 INFO - "project": "", 06:31:16 INFO - "product": "firefox", 06:31:16 INFO - "script_repo_revision": "production", 06:31:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:31:16 INFO - "repository": "", 06:31:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:31:16 INFO - "buildid": "20160509040557", 06:31:16 INFO - "stage_platform": "macosx64", 06:31:16 INFO - "basedir": "/builds/slave/test", 06:31:16 INFO - "nightly_build": "True", 06:31:16 INFO - "buildnumber": 270, 06:31:16 INFO - "slavename": "t-yosemite-r7-0088", 06:31:16 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 06:31:16 INFO - "platform": "yosemite_r7", 06:31:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:31:16 INFO - "branch": "mozilla-central", 06:31:16 INFO - "repo_path": "mozilla-central", 06:31:16 INFO - "revision": "043082cb7bd8", 06:31:16 INFO - "pgo_build": "False", 06:31:16 INFO - "builduid": "5e1b192add0c487182cf725d3895dd3e", 06:31:16 INFO - "slavebuilddir": "test" 06:31:16 INFO - } 06:31:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:31:16 INFO - Running post-action listener: _resource_record_post_action 06:31:16 INFO - ##### 06:31:16 INFO - ##### Finished read-buildbot-config step (success) 06:31:16 INFO - ##### 06:31:16 INFO - ##### 06:31:16 INFO - ##### Running download-and-extract step. 06:31:16 INFO - ##### 06:31:16 INFO - Running pre-action listener: _resource_record_pre_action 06:31:16 INFO - Running main action method: download_and_extract 06:31:16 INFO - mkdir: /builds/slave/test/build/tests 06:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:16 INFO - https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:31:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:31:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:31:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:31:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 06:31:17 INFO - Downloaded 1199 bytes. 06:31:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:31:17 INFO - Using the following test package requirements: 06:31:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:31:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:31:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:31:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'jsshell-mac.zip'], 06:31:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:31:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:31:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:31:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:31:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:31:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:31:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:31:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:17 INFO - https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:31:18 INFO - Downloaded 18146650 bytes. 06:31:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:31:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:31:22 INFO - Return code: 0 06:31:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:31:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:22 INFO - https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:31:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:31:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:31:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:31:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:31:23 INFO - Downloaded 18146650 bytes. 06:31:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:31:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:31:26 INFO - Return code: 0 06:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:26 INFO - https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:31:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:31:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:31:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:31:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:31:27 INFO - Downloaded 10840152 bytes. 06:31:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:31:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 06:31:27 INFO - Return code: 0 06:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:27 INFO - https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 06:31:31 INFO - Downloaded 110557176 bytes. 06:31:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:31:31 INFO - mkdir: /builds/slave/test/properties 06:31:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:31:31 INFO - Writing to file /builds/slave/test/properties/build_url 06:31:31 INFO - Contents: 06:31:31 INFO - build_url:https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:31:31 INFO - Running post-action listener: _resource_record_post_action 06:31:31 INFO - Running post-action listener: set_extra_try_arguments 06:31:31 INFO - ##### 06:31:31 INFO - ##### Finished download-and-extract step (success) 06:31:31 INFO - ##### 06:31:31 INFO - ##### 06:31:31 INFO - ##### Running populate-webroot step. 06:31:31 INFO - ##### 06:31:31 INFO - Running pre-action listener: _resource_record_pre_action 06:31:31 INFO - Running main action method: populate_webroot 06:31:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:31:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:31:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:31:31 INFO - '--tppagecycles', 06:31:31 INFO - '7'], 06:31:31 INFO - 'tests': ['tsvgm']}}, 06:31:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:31:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:31:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:31:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:31:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:31:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:31:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['damp', 'tps']}, 06:31:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'tests': ['damp', 'tps']}, 06:31:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['dromaeo_dom']}, 06:31:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:31:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['a11yr', 06:31:31 INFO - 'ts_paint', 06:31:31 INFO - 'tpaint', 06:31:31 INFO - 'sessionrestore', 06:31:31 INFO - 'sessionrestore_no_auto_restore', 06:31:31 INFO - 'tabpaint']}, 06:31:31 INFO - 'other-e10s': {'tests': ['a11yr', 06:31:31 INFO - 'ts_paint', 06:31:31 INFO - 'tpaint', 06:31:31 INFO - 'sessionrestore', 06:31:31 INFO - 'sessionrestore_no_auto_restore', 06:31:31 INFO - 'tabpaint']}, 06:31:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:31:31 INFO - 'ts_paint', 06:31:31 INFO - 'tpaint', 06:31:31 INFO - 'sessionrestore', 06:31:31 INFO - 'sessionrestore_no_auto_restore', 06:31:31 INFO - 'tabpaint']}, 06:31:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:31:31 INFO - 'ts_paint', 06:31:31 INFO - 'tpaint', 06:31:31 INFO - 'sessionrestore', 06:31:31 INFO - 'sessionrestore_no_auto_restore', 06:31:31 INFO - 'tabpaint']}, 06:31:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['a11yr', 06:31:31 INFO - 'ts_paint', 06:31:31 INFO - 'tpaint', 06:31:31 INFO - 'sessionrestore', 06:31:31 INFO - 'sessionrestore_no_auto_restore', 06:31:31 INFO - 'tabpaint']}, 06:31:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['a11yr', 06:31:31 INFO - 'ts_paint', 06:31:31 INFO - 'tpaint', 06:31:31 INFO - 'sessionrestore', 06:31:31 INFO - 'sessionrestore_no_auto_restore', 06:31:31 INFO - 'tabpaint']}, 06:31:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['tsvgx', 06:31:31 INFO - 'tsvgr_opacity', 06:31:31 INFO - 'tart', 06:31:31 INFO - 'tscrollx', 06:31:31 INFO - 'cart']}, 06:31:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:31:31 INFO - 'tsvgr_opacity', 06:31:31 INFO - 'tart', 06:31:31 INFO - 'tscrollx', 06:31:31 INFO - 'cart']}, 06:31:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'talos_options': ['--disable-e10s'], 06:31:31 INFO - 'tests': ['tp5o']}, 06:31:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'tests': ['tp5o']}, 06:31:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'talos_options': ['--disable-e10s', 06:31:31 INFO - '--xperf_path', 06:31:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:31:31 INFO - 'tests': ['tp5n']}, 06:31:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:31:31 INFO - 'talos_options': ['--xperf_path', 06:31:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:31:31 INFO - 'tests': ['tp5n']}}, 06:31:31 INFO - 'talos.zip': {'path': '', 06:31:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:31:31 INFO - Running post-action listener: _resource_record_post_action 06:31:31 INFO - ##### 06:31:31 INFO - ##### Finished populate-webroot step (success) 06:31:31 INFO - ##### 06:31:31 INFO - ##### 06:31:31 INFO - ##### Running create-virtualenv step. 06:31:31 INFO - ##### 06:31:31 INFO - Running pre-action listener: _resource_record_pre_action 06:31:31 INFO - Running main action method: create_virtualenv 06:31:31 INFO - Creating virtualenv /builds/slave/test/build/venv 06:31:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:31:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:31:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:31:32 INFO - Using real prefix '/tools/python27' 06:31:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:31:32 INFO - Installing distribute.............................................................................................................................................................................................done. 06:31:35 INFO - Installing pip.................done. 06:31:35 INFO - Return code: 0 06:31:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:31: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')]} 06:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31: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')]} 06:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:35 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:35 INFO - 'HOME': '/Users/cltbld', 06:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:35 INFO - 'LOGNAME': 'cltbld', 06:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:35 INFO - 'MOZ_NO_REMOTE': '1', 06:31:35 INFO - 'NO_EM_RESTART': '1', 06:31:35 INFO - 'PAGER': '/bin/cat', 06:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:35 INFO - 'PWD': '/builds/slave/test', 06:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:35 INFO - 'SHELL': '/bin/bash', 06:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:35 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:35 INFO - 'USER': 'cltbld', 06:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:35 INFO - 'XPC_FLAGS': '0x0', 06:31:35 INFO - 'XPC_SERVICE_NAME': '0', 06:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:31:35 INFO - Cleaning up... 06:31:35 INFO - Return code: 0 06:31:35 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 06:31: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')]} 06:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31: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')]} 06:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:35 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 06:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 06:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:35 INFO - 'HOME': '/Users/cltbld', 06:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:35 INFO - 'LOGNAME': 'cltbld', 06:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:35 INFO - 'MOZ_NO_REMOTE': '1', 06:31:35 INFO - 'NO_EM_RESTART': '1', 06:31:35 INFO - 'PAGER': '/bin/cat', 06:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:35 INFO - 'PWD': '/builds/slave/test', 06:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:35 INFO - 'SHELL': '/bin/bash', 06:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:35 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:35 INFO - 'USER': 'cltbld', 06:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:35 INFO - 'XPC_FLAGS': '0x0', 06:31:35 INFO - 'XPC_SERVICE_NAME': '0', 06:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:36 INFO - Downloading/unpacking psutil>=0.7.1 06:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:31:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:31:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:31:39 INFO - Installing collected packages: psutil 06:31:39 INFO - Running setup.py install for psutil 06:31:39 INFO - building 'psutil._psutil_osx' extension 06:31:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:31:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:31:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:31:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:31:40 INFO - building 'psutil._psutil_posix' extension 06:31:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:31:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:31:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:31:40 INFO - ^ 06:31:40 INFO - 1 warning generated. 06:31:40 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:31:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:31:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:31:40 INFO - Successfully installed psutil 06:31:40 INFO - Cleaning up... 06:31:40 INFO - Return code: 0 06:31:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:40 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 06:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:40 INFO - 'HOME': '/Users/cltbld', 06:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:40 INFO - 'LOGNAME': 'cltbld', 06:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:40 INFO - 'MOZ_NO_REMOTE': '1', 06:31:40 INFO - 'NO_EM_RESTART': '1', 06:31:40 INFO - 'PAGER': '/bin/cat', 06:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:40 INFO - 'PWD': '/builds/slave/test', 06:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:40 INFO - 'SHELL': '/bin/bash', 06:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:40 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:40 INFO - 'USER': 'cltbld', 06:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:40 INFO - 'XPC_FLAGS': '0x0', 06:31:40 INFO - 'XPC_SERVICE_NAME': '0', 06:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:31:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:31:43 INFO - Installing collected packages: mozsystemmonitor 06:31:43 INFO - Running setup.py install for mozsystemmonitor 06:31:43 INFO - Successfully installed mozsystemmonitor 06:31:43 INFO - Cleaning up... 06:31:43 INFO - Return code: 0 06:31:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:43 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:43 INFO - 'HOME': '/Users/cltbld', 06:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:43 INFO - 'LOGNAME': 'cltbld', 06:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:43 INFO - 'MOZ_NO_REMOTE': '1', 06:31:43 INFO - 'NO_EM_RESTART': '1', 06:31:43 INFO - 'PAGER': '/bin/cat', 06:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:43 INFO - 'PWD': '/builds/slave/test', 06:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:43 INFO - 'SHELL': '/bin/bash', 06:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:43 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:43 INFO - 'USER': 'cltbld', 06:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:43 INFO - 'XPC_FLAGS': '0x0', 06:31:43 INFO - 'XPC_SERVICE_NAME': '0', 06:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:43 INFO - Downloading/unpacking blobuploader==1.2.4 06:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:46 INFO - Downloading blobuploader-1.2.4.tar.gz 06:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:31:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:31:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:47 INFO - Downloading docopt-0.6.1.tar.gz 06:31:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:31:47 INFO - Installing collected packages: blobuploader, requests, docopt 06:31:47 INFO - Running setup.py install for blobuploader 06:31:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:31:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:31:47 INFO - Running setup.py install for requests 06:31:48 INFO - Running setup.py install for docopt 06:31:48 INFO - Successfully installed blobuploader requests docopt 06:31:48 INFO - Cleaning up... 06:31:48 INFO - Return code: 0 06:31:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:48 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:48 INFO - 'HOME': '/Users/cltbld', 06:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:48 INFO - 'LOGNAME': 'cltbld', 06:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:48 INFO - 'MOZ_NO_REMOTE': '1', 06:31:48 INFO - 'NO_EM_RESTART': '1', 06:31:48 INFO - 'PAGER': '/bin/cat', 06:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:48 INFO - 'PWD': '/builds/slave/test', 06:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:48 INFO - 'SHELL': '/bin/bash', 06:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:48 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:48 INFO - 'USER': 'cltbld', 06:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:48 INFO - 'XPC_FLAGS': '0x0', 06:31:48 INFO - 'XPC_SERVICE_NAME': '0', 06:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:31:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Dj0IIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:31:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-MI4Rw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:31:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-7WIV60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:31:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ZCsdLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:31:48 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Pn01hY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-nTOP1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-raaeW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-34Wmxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-n2H9Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-n5_pGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-sTyqRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3Fi8o8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3meEBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-bQSUFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:49 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-lsEcJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-5jN5eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:31:50 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hVq_te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:31:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:31:50 INFO - Running setup.py install for manifestparser 06:31:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:31:50 INFO - Running setup.py install for mozcrash 06:31:50 INFO - Running setup.py install for mozdebug 06:31:50 INFO - Running setup.py install for mozdevice 06:31:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:31:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:31:50 INFO - Running setup.py install for mozfile 06:31:51 INFO - Running setup.py install for mozhttpd 06:31:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Running setup.py install for mozinfo 06:31:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Running setup.py install for mozInstall 06:31:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Running setup.py install for mozleak 06:31:51 INFO - Running setup.py install for mozlog 06:31:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Running setup.py install for moznetwork 06:31:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:31:51 INFO - Running setup.py install for mozprocess 06:31:52 INFO - Running setup.py install for mozprofile 06:31:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:31:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:31:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:31:52 INFO - Running setup.py install for mozrunner 06:31:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:31:52 INFO - Running setup.py install for mozscreenshot 06:31:52 INFO - Running setup.py install for moztest 06:31:52 INFO - Running setup.py install for mozversion 06:31:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:31:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:31:52 INFO - Cleaning up... 06:31:52 INFO - Return code: 0 06:31:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:52 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:52 INFO - 'HOME': '/Users/cltbld', 06:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:52 INFO - 'LOGNAME': 'cltbld', 06:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:52 INFO - 'MOZ_NO_REMOTE': '1', 06:31:52 INFO - 'NO_EM_RESTART': '1', 06:31:52 INFO - 'PAGER': '/bin/cat', 06:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:52 INFO - 'PWD': '/builds/slave/test', 06:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:52 INFO - 'SHELL': '/bin/bash', 06:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:52 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:52 INFO - 'USER': 'cltbld', 06:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:52 INFO - 'XPC_FLAGS': '0x0', 06:31:52 INFO - 'XPC_SERVICE_NAME': '0', 06:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-cgenen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-epBVr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-3AkjcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-BHHtLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-ob2rIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-9N5DRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-FxVa8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-yntqft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:31:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:31:53 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-n4T3jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-LdHoqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-pUE4F3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-NhgRYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-dIHmB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-Thl2k5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-hJ9FvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-tPntdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:31:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:31:54 INFO - Running setup.py (path:/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/pip-I8UZFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:31:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:57 INFO - Downloading blessings-1.6.tar.gz 06:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:31:57 INFO - Installing collected packages: blessings 06:31:57 INFO - Running setup.py install for blessings 06:31:57 INFO - Successfully installed blessings 06:31:57 INFO - Cleaning up... 06:31:57 INFO - Return code: 0 06:31:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:31:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:31:57 INFO - Reading from file tmpfile_stdout 06:31:57 INFO - Current package versions: 06:31:57 INFO - blessings == 1.6 06:31:57 INFO - blobuploader == 1.2.4 06:31:57 INFO - docopt == 0.6.1 06:31:57 INFO - manifestparser == 1.1 06:31:57 INFO - mozInstall == 1.12 06:31:57 INFO - mozcrash == 0.17 06:31:57 INFO - mozdebug == 0.1 06:31:57 INFO - mozdevice == 0.48 06:31:57 INFO - mozfile == 1.2 06:31:57 INFO - mozhttpd == 0.7 06:31:57 INFO - mozinfo == 0.9 06:31:57 INFO - mozleak == 0.1 06:31:57 INFO - mozlog == 3.1 06:31:57 INFO - moznetwork == 0.27 06:31:57 INFO - mozprocess == 0.22 06:31:57 INFO - mozprofile == 0.28 06:31:57 INFO - mozrunner == 6.11 06:31:57 INFO - mozscreenshot == 0.1 06:31:57 INFO - mozsystemmonitor == 0.0 06:31:57 INFO - moztest == 0.7 06:31:57 INFO - mozversion == 1.4 06:31:57 INFO - psutil == 3.1.1 06:31:57 INFO - requests == 1.2.3 06:31:57 INFO - wsgiref == 0.1.2 06:31:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:57 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:31:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:57 INFO - 'HOME': '/Users/cltbld', 06:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:57 INFO - 'LOGNAME': 'cltbld', 06:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:57 INFO - 'MOZ_NO_REMOTE': '1', 06:31:57 INFO - 'NO_EM_RESTART': '1', 06:31:57 INFO - 'PAGER': '/bin/cat', 06:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:57 INFO - 'PWD': '/builds/slave/test', 06:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:57 INFO - 'SHELL': '/bin/bash', 06:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:57 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:57 INFO - 'USER': 'cltbld', 06:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:57 INFO - 'XPC_FLAGS': '0x0', 06:31:57 INFO - 'XPC_SERVICE_NAME': '0', 06:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:31:58 INFO - Cleaning up... 06:31:58 INFO - Return code: 0 06:31:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:31: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')]} 06:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31: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')]} 06:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:31:58 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:31:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:31:58 INFO - 'HOME': '/Users/cltbld', 06:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:31:58 INFO - 'LOGNAME': 'cltbld', 06:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:31:58 INFO - 'MOZ_NO_REMOTE': '1', 06:31:58 INFO - 'NO_EM_RESTART': '1', 06:31:58 INFO - 'PAGER': '/bin/cat', 06:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:31:58 INFO - 'PWD': '/builds/slave/test', 06:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:31:58 INFO - 'SHELL': '/bin/bash', 06:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:31:58 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:31:58 INFO - 'USER': 'cltbld', 06:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:31:58 INFO - 'XPC_FLAGS': '0x0', 06:31:58 INFO - 'XPC_SERVICE_NAME': '0', 06:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:31:58 INFO - Downloading/unpacking jsonschema 06:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:31:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:32:00 INFO - Downloading/unpacking functools32 (from jsonschema) 06:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:01 INFO - Downloading functools32-3.2.3-2.tar.gz 06:32:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:32:01 INFO - warning: no files found matching '*.txt' 06:32:01 INFO - no previously-included directories found matching 'build' 06:32:01 INFO - no previously-included directories found matching 'dist' 06:32:01 INFO - no previously-included directories found matching '.git*' 06:32:01 INFO - Installing collected packages: jsonschema, functools32 06:32:01 INFO - Running setup.py install for functools32 06:32:01 INFO - warning: no files found matching '*.txt' 06:32:01 INFO - no previously-included directories found matching 'build' 06:32:01 INFO - no previously-included directories found matching 'dist' 06:32:01 INFO - no previously-included directories found matching '.git*' 06:32:01 INFO - Successfully installed jsonschema functools32 06:32:01 INFO - Cleaning up... 06:32:01 INFO - Return code: 0 06:32:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:32:01 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 0x102f37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e7258d570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, '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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:32:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 06:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 06:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:32:01 INFO - 'HOME': '/Users/cltbld', 06:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:32:01 INFO - 'LOGNAME': 'cltbld', 06:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:32:01 INFO - 'MOZ_NO_REMOTE': '1', 06:32:01 INFO - 'NO_EM_RESTART': '1', 06:32:01 INFO - 'PAGER': '/bin/cat', 06:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:32:01 INFO - 'PWD': '/builds/slave/test', 06:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:32:01 INFO - 'SHELL': '/bin/bash', 06:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:32:01 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:32:01 INFO - 'USER': 'cltbld', 06:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:32:01 INFO - 'XPC_FLAGS': '0x0', 06:32:01 INFO - 'XPC_SERVICE_NAME': '0', 06:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:32:01 INFO - Downloading/unpacking flake8 06:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:32:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:32:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:32:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:32: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 06:32: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 06:32: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 06:32: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 06:32: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 06:32: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 06:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:32:06 INFO - no previously-included directories found matching 'docs/_build' 06:32:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:32:06 INFO - Running setup.py install for pep8 06:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:32:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:32:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:32:06 INFO - no previously-included directories found matching 'docs/_build' 06:32:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:32:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:32:06 INFO - Cleaning up... 06:32:06 INFO - Return code: 0 06:32:06 INFO - Running post-action listener: _resource_record_post_action 06:32:06 INFO - Running post-action listener: _start_resource_monitoring 06:32:06 INFO - Starting resource monitoring. 06:32:06 INFO - ##### 06:32:06 INFO - ##### Finished create-virtualenv step (success) 06:32:06 INFO - ##### 06:32:06 INFO - ##### 06:32:06 INFO - ##### Running install step. 06:32:06 INFO - ##### 06:32:06 INFO - Running pre-action listener: _resource_record_pre_action 06:32:06 INFO - Running main action method: install 06:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:32:06 INFO - Reading from file tmpfile_stdout 06:32:06 INFO - Detecting whether we're running mozinstall >=1.0... 06:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:32:06 INFO - Reading from file tmpfile_stdout 06:32:06 INFO - Output received: 06:32:06 INFO - Usage: mozinstall [options] installer 06:32:06 INFO - Options: 06:32:06 INFO - -h, --help show this help message and exit 06:32:06 INFO - -d DEST, --destination=DEST 06:32:06 INFO - Directory to install application into. [default: 06:32:06 INFO - "/builds/slave/test"] 06:32:06 INFO - --app=APP Application being installed. [default: firefox] 06:32:06 INFO - mkdir: /builds/slave/test/build/application 06:32:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:32:40 INFO - Reading from file tmpfile_stdout 06:32:40 INFO - Output received: 06:32:40 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 06:32:40 INFO - Running post-action listener: _resource_record_post_action 06:32:40 INFO - ##### 06:32:40 INFO - ##### Finished install step (success) 06:32:40 INFO - ##### 06:32:40 INFO - ##### 06:32:40 INFO - ##### Running run-tests step. 06:32:40 INFO - ##### 06:32:40 INFO - Running pre-action listener: _resource_record_pre_action 06:32:40 INFO - Running main action method: run_tests 06:32:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:32:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:32:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:32:40 INFO - 2016-05-09 06:32:40.250 screenresolution[1638:7622] starting screenresolution argv=screenresolution get 06:32:40 INFO - 2016-05-09 06:32:40.253 screenresolution[1638:7622] Display 0: 1600x1200x32@60 06:32:40 INFO - 2016-05-09 06:32:40.258 screenresolution[1639:7624] starting screenresolution argv=screenresolution list 06:32:40 INFO - Available Modes on Display 0 06:32:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:32:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:32:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:32:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:32:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:32:40 INFO - Intel Iris: 06:32:40 INFO - Chipset Model: Intel Iris 06:32:40 INFO - Type: GPU 06:32:40 INFO - Bus: Built-In 06:32:40 INFO - VRAM (Dynamic, Max): 1536 MB 06:32:40 INFO - Vendor: Intel (0x8086) 06:32:40 INFO - Device ID: 0x0a2e 06:32:40 INFO - Revision ID: 0x0009 06:32:40 INFO - Displays: 06:32:40 INFO - Display: 06:32:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:32:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:32:40 INFO - Display Serial Number: 1600x1200 60 06:32:40 INFO - Main Display: Yes 06:32:40 INFO - Mirror: Off 06:32:40 INFO - Online: Yes 06:32:40 INFO - Rotation: Supported 06:32:40 INFO - Return code: 0 06:32:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:32:40 INFO - Python 2.7.3 06:32:40 INFO - Return code: 0 06:32:40 INFO - grabbing minidump binary from tooltool 06:32:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:32:40 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 0x102ddbd30>, '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 0x102bffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102dd2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:32:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:32:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:32:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:32:40 INFO - Return code: 0 06:32:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:32:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:32:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:32:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:32:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:32:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:32:41 INFO - Return code: 0 06:32:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0088 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j9JOEV4DLw/Render', 06:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:32:41 INFO - 'HOME': '/Users/cltbld', 06:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:32:41 INFO - 'LOGNAME': 'cltbld', 06:32:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:32:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:32:41 INFO - 'MOZ_NO_REMOTE': '1', 06:32:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:32:41 INFO - 'NO_EM_RESTART': '1', 06:32:41 INFO - 'PAGER': '/bin/cat', 06:32:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:32:41 INFO - 'PWD': '/builds/slave/test', 06:32:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:32:41 INFO - 'SHELL': '/bin/bash', 06:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f6TsSe8MQL/Listeners', 06:32:41 INFO - 'TMPDIR': '/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/', 06:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:32:41 INFO - 'USER': 'cltbld', 06:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:32:41 INFO - 'XPC_FLAGS': '0x0', 06:32:41 INFO - 'XPC_SERVICE_NAME': '0', 06:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:32:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0088', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:32:41 INFO - mozversion application_buildid: 20160509040557 06:32:41 INFO - mozversion application_changeset: 043082cb7bd8490c60815f67fbd1f33323ad7663 06:32:41 INFO - mozversion application_display_name: Nightly 06:32:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:32:41 INFO - mozversion application_name: Firefox 06:32:41 INFO - mozversion application_remotingname: firefox 06:32:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:32:41 INFO - mozversion application_vendor: Mozilla 06:32:41 INFO - mozversion application_version: 49.0a1 06:32:41 INFO - mozversion platform_buildid: 20160509040557 06:32:41 INFO - mozversion platform_changeset: 043082cb7bd8490c60815f67fbd1f33323ad7663 06:32:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:32:41 INFO - mozversion platform_version: 49.0a1 06:32:41 INFO - using testdate: 1462800761 06:32:41 INFO - actual date: 1462800761 06:32:41 INFO - starting webserver on 'localhost:49278' 06:32:41 INFO - SUITE-START | Running 2 tests 06:32:41 INFO - TEST-START | dromaeo_css 06:32:41 INFO - operating with platform_type : mac_ 06:32:41 INFO - Initialising browser for dromaeo_css test... 06:32:41 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp4uPxhZ/profile http://localhost:49278/getInfo.html) 06:32:45 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 06:32:45 INFO - PROCESS | 1657 | colorDepth:24 06:32:45 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 06:32:45 INFO - PROCESS | 1657 | __metrics 06:32:45 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 06:32:45 INFO - TEST-INFO | 1657: exit 0 06:32:45 INFO - Browser initialized. 06:32:45 INFO - Running cycle 1/1 for dromaeo_css test... 06:32:45 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmp4uPxhZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:32:57 INFO - PROCESS | 1661 | RSS: Main: 250134528 06:32:57 INFO - PROCESS | 1661 | 06:33:47 INFO - PROCESS | 1661 | 1462800827262 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:35:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 06:35:59 INFO - PROCESS | 1661 | RSS: Main: 261877760 06:35:59 INFO - PROCESS | 1661 | 06:39:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 06:39:02 INFO - PROCESS | 1661 | RSS: Main: 273833984 06:39:02 INFO - PROCESS | 1661 | 06:42:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 06:42:05 INFO - PROCESS | 1661 | RSS: Main: 275800064 06:42:05 INFO - PROCESS | 1661 | 06:45:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 06:45:07 INFO - PROCESS | 1661 | RSS: Main: 277401600 06:45:07 INFO - PROCESS | 1661 | 06:48:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 06:48:11 INFO - PROCESS | 1661 | RSS: Main: 280236032 06:48:11 INFO - PROCESS | 1661 | 06:51:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 06:51:14 INFO - PROCESS | 1661 | RSS: Main: 281440256 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | __start_tp_report 06:51:14 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 06:51:14 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 06:51:14 INFO - PROCESS | 1661 | |i|pagename|runs| 06:51:14 INFO - PROCESS | 1661 | |0;dojo.html;3484.494748261503;3510.982445087774;3532.646735326467;3542.4686297055437;3570.767900086494;4884;5887.793927212543;5938.317093534245;5944.554158438118;5965.552583556229;4774.546418090275;5763.962486752383;5879.412058794119;5912.763489460427;6014.518838492921;5255.1329030709985;5312.76092575835;5377.677339359631;5408;5459.754311055992;379.3513835168104;5264.552513036397;5350.946490535084;5371.409144994067;5393.460653934595;5339.9733001334935;5343.251944727761;5366.785328586851;5370.758315875795;5377.381601115843;5308.5487733542695;5357.821279318544;5370.462953704638;5392.649477783971;5392.946070539284;5187.988342273258;5263.86840328991;5329.946700532983;5363.06146424378;5371.328583927009;5691.117876729072;5719.914201286984;5737.9384813809575;5783.074708046693;5813.505852002585;6097.222222222223;6234.688265586748;6276.968615156895;6277.403646653561;6277.811665649992;6258.78094266703;6292.528060395489;6311.715972781236;6316.242050953869;6327.14583531222;4929.852104436873;5908.379620139866;5953.196318497034;6002.969985150046;6005.219321488223;6229.688515574203;6315;6428.8071357859335;6482;6490.253620833664;6160.630362178284;6178.258608966863;6207.503399728011;6214.658193799294;6234.563580549318;4941.925871111936;5161.354830646169;5394.109971854678;6091.543134264948;6189.659568723673;4549.545045495424;4922.483139270396;5962.85092872673;6225.879341718534;6288.52836037299;5120.743962801845;6201.596896201732;6283.057541368831;6285.811425657238;6311.495080393557;4720.45714742809;4946.802127914915;5066.442691303953;5069.188929771292;5083.212102124176;4471.128130014616;4478.932816007762;4514.88712782183;4517.254652982221;4568.794404251817;5903.793367232212;5912.556558258148;5982;5991.850203744942;6004.129401236796;6253.1245625612455;6276.372362763687;6302.684865756693;6342.746290148342;6347.270063942613;6015.608985415934;6037.1246169305205;6044.607100538451;6047.969760151259;6051.962299905286;4867.318575399447;5286;5418.2685337479725;5484.945150548523;5530.806421775219;6358.237011558643;6358.872822543523;6372.585781924253;6423.80728578134;6492.350764923659;6370.872582548416;6372.745090196526;6383.744650214126;6384.680765961776;6400.359964003563;6505.479561635152;6547.639879806466;6567.146270984741;6570.737170513317;6577.2436169841085;6171.691415429121;6173.536984726074;6205.720742566686;6269.655168965751;6302.212223472066;6315.5894866833505;6320.715567799552;6330.39861213184;6333;6349.904751428639;6092.543059270483;6198.318184999556;6232.8130156095385;6254.843628909131;6277.71750271248;6119.571629985859;6135.508280839814;6149.585595313014;6171.1668924694595;6232.844178895382;5004.47453017428;6093.6039157454625;6123.7244324006415;6151.292601350902;6207.472364848858;6120.846978825565;6217.906731399123;6223.222097237845;6245.5628106033955;6246.312905580292;5524.530414914777;5865.5600829937075;6114.38856114385;6140.692965351804;6146.907796382969 06:51:14 INFO - PROCESS | 1661 | |1;ext.html;504.75014867640544;512.96125346174;525.9368875734913;571.5513322042195;572.4647454629924;38774.806125969364;39637.40543891844;39663.40504892425;39933.40099898504;40059.59940400595;40412.59587404125;40784.18431631366;41237.76286711396;41321.173576528454;41326.58673413265;9520.952395238015;9656;9665.178459830888;9666.516674166298;9717.174040206592;9579.137877591016;9655.32412731109;9655.710328690151;9686.564104615309;9693.660721874736;9653.855192172123;9690.75773105671;9705.320627556073;9716.882558505757;9717.90282097177;9564.282678799118;9644.855327170062;9645.855312170323;9691.418514889094;9734.805303893952;8372.455790373606;9270.860937085949;9456.952715236448;9519.714408567754;9526.285528585384;30156.396872062436;38479.61520384788;38703.612963870284;38753.41869871954;38793.612063879285;3157.857896394668;3175.04539688067;3204.4071846708434;3369.9503723307253;4055.2497787909333;27242.5913611296;28106.297342566395;28602.856985715145;28915;29862.25344366408;2205.8125059369972;2748.2167582239167;2756.834589924611;2836.602875597418;2874.985625071882;456693;461209;462491;462757;464984;63412;63604.04593931095;64133.67933160398;64224.67887660625;64265.678671607275;35949;37475.437868431996;37506;66641.00038499426;66997.6650116756;16719.6656066878;17059.31762729502;17226.483205503853;17250.396236131674;17321.04734239629;13055.673608159872;13067.215967042008;13132.277724725232;13147.276899770413;13158.473661053451;19775.703364449546;19906;19927.402177934684;19930;19975.700364494543;7172.852343624995;7471.887921681179;7568.508046976929;7991.72028978983;8258.174182581814;773.6363908962778;790.8734602463578;841.2975165736678;873.611242996873;896.0636135238691;29188.562171567442;29798;29924.401511969638;30139.09582712522;30409.087727368213;1036.9637062702768;1062.1874266186373;1082.9350238985528;1096.5175322857917;1105.9612913548149;15362.462313819186;15394.538163855103;15448.613784655248;15570.610734731496;15693.921530392276;134194;163642;173219.40170897447;173422;656344;58150.22023568539;58499.41500585025;59206;60170.69914650486;60632;1432.8567143285588;1445.7831325301288;1473.3738161281199;1475.2918599072505;1477.2170749502782;28934.27664308325;29708.257293567833;29771.404571908875;29830;29946.850265748533;50763.73090672763;57141.85716285734;59253;59807.401925981925;61762.073568895605;630519;635376.7618133343;638135;641274;641453;26953.460930781668;42149.578504214565;42634;43088.33749131359;43100.353494696974;44244.55755442404;45378.54621453743;46138.077238454374;46301;46468.53531464777;25894.482110357312;37408;38332;38786.22427551377;39319.80340098281;37084.88745337644;37314.62685373111;37550.624493754716;37906.81046594749;38025.429618555194;29497;37140.25719485541;38492.230155397294;38684.03289917774;38839.80580097081;26567.072384410214;28472;32961.01116966495;33774.83112584421;33918.66081339155;19476.262714182798;24068.315217934578;24507;25436.872815635805;25539.48921021532 06:51:14 INFO - PROCESS | 1661 | |2;jquery.html;358.9784612923225;388.89888628956464;396.6350957119451;407.03126558790115;413.4212103055722;15119.848801511982;19295.710564341545;21051.894740526313;21052.473688157803;21493.817840018834;20598.794012059876;21268;21317.68023479649;21330.57338853222;21644.35066947994;4836.2987366831785;5767.9423205768035;5920.052791553352;5988.940110598882;6007.609505382136;5756.568257380693;5922.348541660435;5929.280508652507;5949.940500594982;5952.613080149798;5878.088896221093;5946.791862284799;5957.523398128161;5978.22283103196;5985.25184351956;5680.801171959003;5900.085486749539;5980.953333166678;5984.251968503937;5994.520438364921;5160.380754309507;5193.584513238932;5209.583233341324;5234.7644356004075;5238.371395432534;25843;26430.339241518923;27121.864390678118;27153.1854044379;27221.45557088867;2148.0977989244557;2148.817350525207;2234.240358278193;2387.9403014924765;2586.961195582068;15052.54842354731;15342.463013794575;15380.692386152326;15442.073475591387;15676.608084797857;1742.8257174282596;1775.1568005197428;1810.248746770089;1858.50749551369;1901.3345329134831;116959;120259;120595.99999999913;121813;123328.15007774891;11194;11429.771404571862;11648.650540483799;11748.060155187735;12025.759484810254;14420.85579144195;14440.989130760956;14510.637234069232;14540.200288983995;14580.854191458162;8757;8987.41581797181;8992.370534062553;9000.8649870252;9082.046385129599;8950.552472376356;9002.729918102468;9008.189262966234;9131.452112873249;9131.589078491485;6926.480513961473;6933.5493192942295;7027.5080744348425;7047.083879095787;7146.428285737233;1697.9660406792043;1894.7157926310888;1934.071645610115;1939.9921081680675;1940.6506828770675;866.9913300867037;988.8566157907063;996.5366104761321;1000.5147503460815;1066.632011955874;19461.12424940881;21510.892445537986;21518.784812151993;21807.902332282094;21928.89035554812;952.9714108576753;957.2963871554402;980.3921568627354;993.2302465589227;1096.1285777806504;8858.64565417389;11439.714007149722;11480.598179063692;11498.42507874603;11684.70788230284;620985;653815;656388;657699;666463;108514;129652;140549;148705;154511.45488545197;1002.8796544414716;1049.9265051446478;1056.6090546497844;1062.5537274344795;1065.3288428290127;18349.54126146857;18670.253189872252;18766.53083672919;18798.906005469886;18866.52833679114;50514;55231;56273.8745225085;56368.87262254814;57429;82442.58778706308;83240;84047.5797621008;84414.57792710997;86136.56931715301;6719.496037797283;6769.93230067693;7980.441369104107;7993.920060799318;8094.433389662667;10918.563257469612;12533.435995380412;12559.246445213317;12573.811392828931;12944.676383090498;10729.409882456539;10943.562257509611;10991.780164396827;11139.83290250663;11200.607978720705;10695.197860160675;10764.40795756241;10784.245102842726;10894.945525272324;10980.780384392427;11087.611933582608;11387.316760994367;11487.712807179887;11508.309501429942;11553.13351498624;6601.801945941629;7998.800029999296;8210.343172546302;8234.547099909563;8480.194381533744;6715.664216789239;8266.628001739817;9500.61997520119;10923.672289831318;10999.450027498753 06:51:14 INFO - PROCESS | 1661 | |3;mootools.html;1540.4300408848724;1606.8634166095885;1862.5529872830525;1943.4072607854607;1970.9507262318432;7886.447948643583;8365.247127758501;8438.535880526568;8834.05650756834;8863.335249856254;8516.531590762506;8553.914460855405;8687.522186279752;8736.4758114513;8750.737477875675;2396.2332053742807;2413.8672373019476;2416.734159242482;2422.321749910028;2427.0655797517993;2405.2663937499055;2412.867292298923;2422.8546287222734;2436.50051739393;2450.7181674107437;2399.6520504526834;2404.735479097307;2419.9395015124674;2425.8301918865686;2434.8904299306487;2391.1272385579136;2400.6159014557675;2408.8313818032743;2415.1064106280787;2416.927492175229;2338.286822519131;2361.681173041635;2380.964285535701;2383.3445802404303;2399.928002159938;8604.31165506758;8624.56877156146;8732.47605143687;8778.122187781235;8796.912030879674;4817.855464336076;5281;5380.946190538123;5405.891882162374;5411.998780225671;3376.763626546143;3530.452779819132;3559.4482855157494;3577.1951310955087;3587.964120358789;1027.1679939249232;1041.536516250269;1048.9527971241237;1050.56926660069;1066.4507778494083;11492;11497.425128743529;11602.12984026193;11634.883651163465;11661.833816618355;4544.454665440168;4590.609798167126;4686.156491831435;4687.69529980557;4692.0146769178855;9219.861702074475;9221.861672074925;9225.031371706009;9254.907450925404;9257.583408746623;5245.659032162897;5247.107991641354;5303.0719624066405;5332.760025798849;5333.093374126408;3972.880813575597;4358.389825424443;4941.085899108676;4960.454350021495;4976.776045077981;3798.3352913240074;3812.694984401241;3884.4173373994126;3913.9608603914166;3927.253821773911;1194.5759255464186;1200.8318835363057;1213.8968187704145;1217.6164508179895;1230.138902768066;936.0218571592702;942.9858552121724;951.1724799424545;958.3626888119322;980.5734505489992;5530.5299049580435;5757.366689664133;5837.124431335334;6064.241969753781;6114.449699527064;392.6760422651267;445.98662040138845;462.6160286961814;467.21274652211133;473.08222049224395;4001.559828418872;4036.233115708006;4043.939340909889;4071.307877660805;4089;8643.222110010129;8826.338024648177;9466.479343636167;10247.436390998566;10813.297135686313;10432.113270372103;10631;10645.467726613639;10670.30643008202;10802.891971080346;6748.59508429486;8209.83580328402;8268.83462330762;8304.211099945496;8314;5802.47777700009;5876.794312199053;5898.203742494708;5999.880002400015;6127.601705889103;9465.763355916157;9880.505974701093;10106;10146.441945693057;10220.386776793417;10017.499125043865;10100.797984040424;10118.190544756255;10129;10208.795824083329;5524.640898341595;6956.130483689539;7440.330370266485;7503.812404689927;7682.6926922924695;8283.58582070882;8329.167083291622;8373.706920258006;8378.329733621418;8488.108748581557;7284.125904891447;7391.445641577011;7460;7495.213002634645;7544.924550754642;7330.743423980349;7357.411407087313;7392.186859445565;7469.290417410337;7562.773116806505;6924.757633482804;6987.475939304471;7004.124484439445;7015.6842942066705;7036.085308909809;4590.449146102489;4681.882952926067;4696.906061878812;4811.78346974394;5067.873303167303;4887.3890763654545;5047.798088076583;5050.065737838511;5072.9746351268;5270.762815673227 06:51:14 INFO - PROCESS | 1661 | |4;prototype.html;313.7772181750957;318.3283272295458;319.27843074651287;325.1286552040532;326.16664422400754;4991.700497970124;5247.691015952988;5374.925014997001;5429.4027656957705;5433.673979561229;4405.647548196144;4554.954450455494;5199.974000130003;5402.892871780009;5441.5102640762425;3174.365126974614;3265.5591495148087;3751.636469024511;4097.89755256118;4138.710290279681;3241.9027429177045;4030.9596904031023;4079.6940229482757;4097.856575019875;4124.298869192245;3480.303939212155;3539.4867744177077;3870.322693528635;3973.6821054315587;4064.9390259146135;3793.5827059023486;3961.1483531040676;4086.346184610463;4106.7946602669745;4117.258893399187;3061.3877224554954;3473.9826300868585;3551.9289614207573;3572.964270357315;3579.8926032219074;5071.239314102877;5137.5633071188995;5149.201873709581;5160.845174644766;5166.560842328407;4063;4451.888702782424;4539.455265368144;4574.902023514366;4739.578126562018;4267.162337045861;5651.265335506358;5651.406602306781;5677.205191273225;5708.6604336463215;3844.788536630484;3908.6286802753975;3930.860050585327;3946.210757848442;3962.7820469874146;5036.622253331015;5229.823289759774;5796.507296879812;5953.737579719722;6048.580567772882;4004.0390306326854;4014.3340513687076;4030.375291829771;4047.5952404759287;4052.3111071117983;4070.3894415837863;4092.6725861930972;4104.897377565585;4116.115035267432;4120.743173332163;3989.3218152914073;4716.504766999485;4717.386739723814;4754.7147171169145;4810.350602668595;4936.777844996984;5460.699661518575;5660.2075709400715;5743.253377060955;5786.855328616818;1140.8516892803882;1158.530795028015;1169.5438778876246;1178.3283528388909;1256.214865708932;747.5626758346319;874.6676263020012;917.6191880369705;921.7833809054866;933.6358820060182;4201.68487363449;4860.076585448754;4918.05736733988;5103.030424219386;5341.65279256847;4989;5194.376674798973;5348.866278343073;5887.8233652990475;5963;4525.547445255514;5290.391604965402;5311.7078560678765;5330.307060082165;5336.893262134736;2809.676887157962;2810.2271875234474;2925.8683359248885;2964.333025069342;2981.344104297051;4913.213885778258;4995.2526110639;5163.889763700822;5189.351331083614;5918.674472903946;5017.523335283142;5100.877806882554;5598.104303311532;5634.070378387521;6060.696920178513;5795.333536643256;6101.084837274533;6125.693715314216;6171.9691401543605;6245.28179259391;5348.743045384409;5373.946260537501;5439.660945793189;5491.697956612425;6019.742596526303;4434.600885920283;4894.1402267138255;4990;5352.652077614943;5514.99432809128;5377.650452720561;5753.568482363756;5914.970425147847;6215.042261687554;6263.968680156752;3469.7918124911575;3854.325493038651;3861.467735049082;3951.407288906687;3970.7419017763755;5921.703914804329;5939.940600593939;5968.970155149196;6018.86330352736;6020.608660437058;4764.642651801199;5710.628809127394;5755.7697692093525;5765.048766953406;5773.595848290579;4341.327094300388;4826.734529600905;5715.799947001835;5769.480746732815;5776.335721391925;5634.887302254014;5637.351704554028;5642.576806739429;5651.078633264709;5662.405447427961;4641.373414589122;4730.030343779448;5441.483059109379;5594.678208543409;5715.714214289352;4889.682170658896;5322.3010008045585;5597.188407680945;5603.80386686464;5651.208830763779 06:51:14 INFO - PROCESS | 1661 | |5;yui.html;640.9006603976384;674.6053558668181;739.5156172706879;756.625470392156;758.5486635451904;15034.947553671247;15507.457238996638;15623.375064997415;15727.84272157278;15780.605484862885;14337.21145337006;14412.423503059865;14528.273586320669;14537.418503259856;14606.269686515685;757.2843662738715;902.9661038111342;910.995445022774;921.8294615496121;923.7736754495124;711.1892442615397;888.6756333938131;914.5107367558358;917.0829170829171;922.1101636920371;947.6304241345881;956.5456408206117;976.7313988653107;983.3706427886159;984.8227319082562;940.501534186882;944.1031020530489;951.2009911674227;973.3478569358547;975.6682727872521;704.883694190458;710.5132983906041;713.5789883968483;728.02808250985;730.2332550821669;15009.849901500955;15105.848941510554;15108.791296696238;15110.320035598315;15159.848401515954;12673.556425525154;12685.365731713377;12754.170978886343;12769.233845969271;12963.15739476931;9155.725328240163;9847.704568862946;9955.55200016001;10014.198864090855;10251.743706407326;2496.737842526527;3440.896773096811;3493.7379696522607;3503.6496350365014;3509.736769742254;82869.58565207195;83427;85167.57416212879;85529;85679.57160214221;8448.366372522085;9249.722508324761;9467.810643787223;9681.225501959967;9706.272029597809;19586.902065489867;20109.798902011084;20938.162473501194;22373.888130559568;22472.32583022512;5671.376148623648;6796.898046529306;7504.099508058958;7553.697852085965;7646.9617651911385;3831.1571454280015;3832.7125465590193;3978.6419222270133;3984.541777695544;3997.0806714455257;3260.9021729348156;3291.3581851539197;3344.7491438142238;3345.9330813383594;3363.142398688368;548.8775454196178;646.6992848325457;649.250116115887;655.3315618069543;656.7832615236961;472.5723220485466;537.6021743909479;590.2739630254811;596.5674885707828;602.668532307238;7996.200379962073;8057.6776928923355;8162.551059691774;8209.71266005687;8286.958565207256;513.599392473871;551.3686828581252;590.10598942602;609.107657282075;621.9782307619303;3785.564660064073;3807.276617442677;3826.6556009959236;3834.1564855731694;3840.807959602009;74221.6288918552;77412.22587774163;77912.61043694745;86200.56899715461;88356;78236;78909.99999999885;79352.60323698344;79962.60018699986;80180.59909700415;65508.689826204165;67938.66030669815;70227.64886175537;71421;72360.63819680868;7936.880946785687;8090.1909809018625;8202;8258.091609922783;8286.627101780314;30191.396172076875;38920.02699932411;39691.40462893117;41741.79129104335;44074;57622.7118864403;58445;58731.412685872594;59047;61027.69486152541;9604.183644390305;10780.137588993019;10989.395583242678;11023.393713345522;11271.098312134845;9613.759656008655;11843.46704398287;11923.642290730944;11958.64124076279;12005.939970300093;11417.257878237888;11453.713657158638;11489.54041838317;11721.238119522544;11749.353785541878;11489.253198542068;11496.137789665976;11557.133215009077;11918.404079795802;11929.642110736691;11121.165912556753;11309.321440713584;11317.603883864025;11325.886741132483;11622;8815.382923195315;9213.401128926598;9224;9334.439933604006;9789.314747967574;8882.245009174292;9967.750806229902;10078.092971632588;10089.445080520642;10159.79680406373 06:51:14 INFO - PROCESS | 1661 | __end_tp_report 06:51:14 INFO - PROCESS | 1661 | __start_cc_report 06:51:14 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,410 06:51:14 INFO - PROCESS | 1661 | __end_cc_report 06:51:14 INFO - PROCESS | 1661 | __startTimestamp1462801874230__endTimestamp 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | ------- Summary: start ------- 06:51:14 INFO - PROCESS | 1661 | Number of tests: 6 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5755.71 Median:6044.61 stddev:784.12 (13.0%) stddev-sans-first:766.01 06:51:14 INFO - PROCESS | 1661 | Values: 3484.5 3511.0 3532.6 3542.5 3570.8 4884.0 5887.8 5938.3 5944.6 5965.6 4774.5 5764.0 5879.4 5912.8 6014.5 5255.1 5312.8 5377.7 5408.0 5459.8 379.4 5264.6 5350.9 5371.4 5393.5 5340.0 5343.3 5366.8 5370.8 5377.4 5308.5 5357.8 5370.5 5392.6 5392.9 5188.0 5263.9 5329.9 5363.1 5371.3 5691.1 5719.9 5737.9 5783.1 5813.5 6097.2 6234.7 6277.0 6277.4 6277.8 6258.8 6292.5 6311.7 6316.2 6327.1 4929.9 5908.4 5953.2 6003.0 6005.2 6229.7 6315.0 6428.8 6482.0 6490.3 6160.6 6178.3 6207.5 6214.7 6234.6 4941.9 5161.4 5394.1 6091.5 6189.7 4549.5 4922.5 5962.9 6225.9 6288.5 5120.7 6201.6 6283.1 6285.8 6311.5 4720.5 4946.8 5066.4 5069.2 5083.2 4471.1 4478.9 4514.9 4517.3 4568.8 5903.8 5912.6 5982.0 5991.9 6004.1 6253.1 6276.4 6302.7 6342.7 6347.3 6015.6 6037.1 6044.6 6048.0 6052.0 4867.3 5286.0 5418.3 5484.9 5530.8 6358.2 6358.9 6372.6 6423.8 6492.4 6370.9 6372.7 6383.7 6384.7 6400.4 6505.5 6547.6 6567.1 6570.7 6577.2 6171.7 6173.5 6205.7 6269.7 6302.2 6315.6 6320.7 6330.4 6333.0 6349.9 6092.5 6198.3 6232.8 6254.8 6277.7 6119.6 6135.5 6149.6 6171.2 6232.8 5004.5 6093.6 6123.7 6151.3 6207.5 6120.8 6217.9 6223.2 6245.6 6246.3 5524.5 5865.6 6114.4 6140.7 6146.9 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:60450.62 Median:38816.71 stddev:132305.18 (340.8%) stddev-sans-first:132599.77 06:51:14 INFO - PROCESS | 1661 | Values: 504.8 513.0 525.9 571.6 572.5 38774.8 39637.4 39663.4 39933.4 40059.6 40412.6 40784.2 41237.8 41321.2 41326.6 9521.0 9656.0 9665.2 9666.5 9717.2 9579.1 9655.3 9655.7 9686.6 9693.7 9653.9 9690.8 9705.3 9716.9 9717.9 9564.3 9644.9 9645.9 9691.4 9734.8 8372.5 9270.9 9457.0 9519.7 9526.3 30156.4 38479.6 38703.6 38753.4 38793.6 3157.9 3175.0 3204.4 3370.0 4055.2 27242.6 28106.3 28602.9 28915.0 29862.3 2205.8 2748.2 2756.8 2836.6 2875.0 456693.0 461209.0 462491.0 462757.0 464984.0 63412.0 63604.0 64133.7 64224.7 64265.7 35949.0 37475.4 37506.0 66641.0 66997.7 16719.7 17059.3 17226.5 17250.4 17321.0 13055.7 13067.2 13132.3 13147.3 13158.5 19775.7 19906.0 19927.4 19930.0 19975.7 7172.9 7471.9 7568.5 7991.7 8258.2 773.6 790.9 841.3 873.6 896.1 29188.6 29798.0 29924.4 30139.1 30409.1 1037.0 1062.2 1082.9 1096.5 1106.0 15362.5 15394.5 15448.6 15570.6 15693.9 134194.0 163642.0 173219.4 173422.0 656344.0 58150.2 58499.4 59206.0 60170.7 60632.0 1432.9 1445.8 1473.4 1475.3 1477.2 28934.3 29708.3 29771.4 29830.0 29946.9 50763.7 57141.9 59253.0 59807.4 61762.1 630519.0 635376.8 638135.0 641274.0 641453.0 26953.5 42149.6 42634.0 43088.3 43100.4 44244.6 45378.5 46138.1 46301.0 46468.5 25894.5 37408.0 38332.0 38786.2 39319.8 37084.9 37314.6 37550.6 37906.8 38025.4 29497.0 37140.3 38492.2 38684.0 38839.8 26567.1 28472.0 32961.0 33774.8 33918.7 19476.3 24068.3 24507.0 25436.9 25539.5 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:37239.47 Median:12081.57 stddev:108561.29 (898.6%) stddev-sans-first:108830.51 06:51:14 INFO - PROCESS | 1661 | Values: 359.0 388.9 396.6 407.0 413.4 15119.8 19295.7 21051.9 21052.5 21493.8 20598.8 21268.0 21317.7 21330.6 21644.4 4836.3 5767.9 5920.1 5988.9 6007.6 5756.6 5922.3 5929.3 5949.9 5952.6 5878.1 5946.8 5957.5 5978.2 5985.3 5680.8 5900.1 5981.0 5984.3 5994.5 5160.4 5193.6 5209.6 5234.8 5238.4 25843.0 26430.3 27121.9 27153.2 27221.5 2148.1 2148.8 2234.2 2387.9 2587.0 15052.5 15342.5 15380.7 15442.1 15676.6 1742.8 1775.2 1810.2 1858.5 1901.3 116959.0 120259.0 120596.0 121813.0 123328.2 11194.0 11429.8 11648.7 11748.1 12025.8 14420.9 14441.0 14510.6 14540.2 14580.9 8757.0 8987.4 8992.4 9000.9 9082.0 8950.6 9002.7 9008.2 9131.5 9131.6 6926.5 6933.5 7027.5 7047.1 7146.4 1698.0 1894.7 1934.1 1940.0 1940.7 867.0 988.9 996.5 1000.5 1066.6 19461.1 21510.9 21518.8 21807.9 21928.9 953.0 957.3 980.4 993.2 1096.1 8858.6 11439.7 11480.6 11498.4 11684.7 620985.0 653815.0 656388.0 657699.0 666463.0 108514.0 129652.0 140549.0 148705.0 154511.5 1002.9 1049.9 1056.6 1062.6 1065.3 18349.5 18670.3 18766.5 18798.9 18866.5 50514.0 55231.0 56273.9 56368.9 57429.0 82442.6 83240.0 84047.6 84414.6 86136.6 6719.5 6769.9 7980.4 7993.9 8094.4 10918.6 12533.4 12559.2 12573.8 12944.7 10729.4 10943.6 10991.8 11139.8 11200.6 10695.2 10764.4 10784.2 10894.9 10980.8 11087.6 11387.3 11487.7 11508.3 11553.1 6601.8 7998.8 8210.3 8234.5 8480.2 6715.7 8266.6 9500.6 10923.7 10999.5 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5626.95 Median:4814.82 stddev:3125.60 (64.9%) stddev-sans-first:3119.28 06:51:14 INFO - PROCESS | 1661 | Values: 1540.4 1606.9 1862.6 1943.4 1971.0 7886.4 8365.2 8438.5 8834.1 8863.3 8516.5 8553.9 8687.5 8736.5 8750.7 2396.2 2413.9 2416.7 2422.3 2427.1 2405.3 2412.9 2422.9 2436.5 2450.7 2399.7 2404.7 2419.9 2425.8 2434.9 2391.1 2400.6 2408.8 2415.1 2416.9 2338.3 2361.7 2381.0 2383.3 2399.9 8604.3 8624.6 8732.5 8778.1 8796.9 4817.9 5281.0 5380.9 5405.9 5412.0 3376.8 3530.5 3559.4 3577.2 3588.0 1027.2 1041.5 1049.0 1050.6 1066.5 11492.0 11497.4 11602.1 11634.9 11661.8 4544.5 4590.6 4686.2 4687.7 4692.0 9219.9 9221.9 9225.0 9254.9 9257.6 5245.7 5247.1 5303.1 5332.8 5333.1 3972.9 4358.4 4941.1 4960.5 4976.8 3798.3 3812.7 3884.4 3914.0 3927.3 1194.6 1200.8 1213.9 1217.6 1230.1 936.0 943.0 951.2 958.4 980.6 5530.5 5757.4 5837.1 6064.2 6114.4 392.7 446.0 462.6 467.2 473.1 4001.6 4036.2 4043.9 4071.3 4089.0 8643.2 8826.3 9466.5 10247.4 10813.3 10432.1 10631.0 10645.5 10670.3 10802.9 6748.6 8209.8 8268.8 8304.2 8314.0 5802.5 5876.8 5898.2 5999.9 6127.6 9465.8 9880.5 10106.0 10146.4 10220.4 10017.5 10100.8 10118.2 10129.0 10208.8 5524.6 6956.1 7440.3 7503.8 7682.7 8283.6 8329.2 8373.7 8378.3 8488.1 7284.1 7391.4 7460.0 7495.2 7544.9 7330.7 7357.4 7392.2 7469.3 7562.8 6924.8 6987.5 7004.1 7015.7 7036.1 4590.4 4681.9 4696.9 4811.8 5067.9 4887.4 5047.8 5050.1 5073.0 5270.8 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4525.68 Median:5006.39 stddev:1410.09 (28.2%) stddev-sans-first:1378.15 06:51:14 INFO - PROCESS | 1661 | Values: 313.8 318.3 319.3 325.1 326.2 4991.7 5247.7 5374.9 5429.4 5433.7 4405.6 4555.0 5200.0 5402.9 5441.5 3174.4 3265.6 3751.6 4097.9 4138.7 3241.9 4031.0 4079.7 4097.9 4124.3 3480.3 3539.5 3870.3 3973.7 4064.9 3793.6 3961.1 4086.3 4106.8 4117.3 3061.4 3474.0 3551.9 3573.0 3579.9 5071.2 5137.6 5149.2 5160.8 5166.6 4063.0 4451.9 4539.5 4574.9 4739.6 4267.2 5651.3 5651.4 5677.2 5708.7 3844.8 3908.6 3930.9 3946.2 3962.8 5036.6 5229.8 5796.5 5953.7 6048.6 4004.0 4014.3 4030.4 4047.6 4052.3 4070.4 4092.7 4104.9 4116.1 4120.7 3989.3 4716.5 4717.4 4754.7 4810.4 4936.8 5460.7 5660.2 5743.3 5786.9 1140.9 1158.5 1169.5 1178.3 1256.2 747.6 874.7 917.6 921.8 933.6 4201.7 4860.1 4918.1 5103.0 5341.7 4989.0 5194.4 5348.9 5887.8 5963.0 4525.5 5290.4 5311.7 5330.3 5336.9 2809.7 2810.2 2925.9 2964.3 2981.3 4913.2 4995.3 5163.9 5189.4 5918.7 5017.5 5100.9 5598.1 5634.1 6060.7 5795.3 6101.1 6125.7 6172.0 6245.3 5348.7 5373.9 5439.7 5491.7 6019.7 4434.6 4894.1 4990.0 5352.7 5515.0 5377.7 5753.6 5915.0 6215.0 6264.0 3469.8 3854.3 3861.5 3951.4 3970.7 5921.7 5939.9 5969.0 6018.9 6020.6 4764.6 5710.6 5755.8 5765.0 5773.6 4341.3 4826.7 5715.8 5769.5 5776.3 5634.9 5637.4 5642.6 5651.1 5662.4 4641.4 4730.0 5441.5 5594.7 5715.7 4889.7 5322.3 5597.2 5603.8 5651.2 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:17518.78 Median:599.62 stddev:24419.94 (4072.6%) stddev-sans-first:24455.56 06:51:14 INFO - PROCESS | 1661 | Values: 640.9 674.6 739.5 756.6 758.5 15034.9 15507.5 15623.4 15727.8 15780.6 14337.2 14412.4 14528.3 14537.4 14606.3 757.3 903.0 911.0 921.8 923.8 711.2 888.7 914.5 917.1 922.1 947.6 956.5 976.7 983.4 984.8 940.5 944.1 951.2 973.3 975.7 704.9 710.5 713.6 728.0 730.2 15009.8 15105.8 15108.8 15110.3 15159.8 12673.6 12685.4 12754.2 12769.2 12963.2 9155.7 9847.7 9955.6 10014.2 10251.7 2496.7 3440.9 3493.7 3503.6 3509.7 82869.6 83427.0 85167.6 85529.0 85679.6 8448.4 9249.7 9467.8 9681.2 9706.3 19586.9 20109.8 20938.2 22373.9 22472.3 5671.4 6796.9 7504.1 7553.7 7647.0 3831.2 3832.7 3978.6 3984.5 3997.1 3260.9 3291.4 3344.7 3345.9 3363.1 548.9 646.7 649.3 655.3 656.8 472.6 537.6 590.3 596.6 602.7 7996.2 8057.7 8162.6 8209.7 8287.0 513.6 551.4 590.1 609.1 622.0 3785.6 3807.3 3826.7 3834.2 3840.8 74221.6 77412.2 77912.6 86200.6 88356.0 78236.0 78910.0 79352.6 79962.6 80180.6 65508.7 67938.7 70227.6 71421.0 72360.6 7936.9 8090.2 8202.0 8258.1 8286.6 30191.4 38920.0 39691.4 41741.8 44074.0 57622.7 58445.0 58731.4 59047.0 61027.7 9604.2 10780.1 10989.4 11023.4 11271.1 9613.8 11843.5 11923.6 11958.6 12005.9 11417.3 11453.7 11489.5 11721.2 11749.4 11489.3 11496.1 11557.1 11918.4 11929.6 11121.2 11309.3 11317.6 11325.9 11622.0 8815.4 9213.4 9224.0 9334.4 9789.3 8882.2 9967.8 10078.1 10089.4 10159.8 06:51:14 INFO - PROCESS | 1661 | -------- Summary: end -------- 06:51:14 INFO - PROCESS | 1661 | 06:51:14 INFO - TEST-INFO | 1661: exit 0 06:51:14 INFO - TEST-OK | dromaeo_css | took 1112900ms 06:51:14 INFO - TEST-START | kraken 06:51:14 INFO - operating with platform_type : mac_ 06:51:14 INFO - Initialising browser for kraken test... 06:51:14 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpRtGPzF/profile http://localhost:49278/getInfo.html) 06:51:17 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 06:51:17 INFO - PROCESS | 1672 | colorDepth:24 06:51:17 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 06:51:17 INFO - PROCESS | 1672 | __metrics 06:51:17 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 06:51:18 INFO - TEST-INFO | 1672: exit 0 06:51:18 INFO - Browser initialized. 06:51:18 INFO - Running cycle 1/1 for kraken test... 06:51:18 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/T/tmpRtGPzF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:51:30 INFO - PROCESS | 1676 | RSS: Main: 249946112 06:51:30 INFO - PROCESS | 1676 | 06:51:33 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 06:51:34 INFO - PROCESS | 1676 | RSS: Main: 261558272 06:51:34 INFO - PROCESS | 1676 | 06:51:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 06:51:37 INFO - PROCESS | 1676 | RSS: Main: 266817536 06:51:37 INFO - PROCESS | 1676 | 06:51:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 06:51:41 INFO - PROCESS | 1676 | RSS: Main: 267161600 06:51:41 INFO - PROCESS | 1676 | 06:51:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 06:51:44 INFO - PROCESS | 1676 | RSS: Main: 267886592 06:51:44 INFO - PROCESS | 1676 | 06:51:46 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:51:47 INFO - PROCESS | 1676 | RSS: Main: 268431360 06:51:47 INFO - PROCESS | 1676 | 06:51:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 06:51:50 INFO - PROCESS | 1676 | RSS: Main: 268886016 06:51:50 INFO - PROCESS | 1676 | 06:51:54 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 06:51:54 INFO - PROCESS | 1676 | RSS: Main: 268972032 06:51:54 INFO - PROCESS | 1676 | 06:51:58 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 06:51:58 INFO - PROCESS | 1676 | RSS: Main: 268918784 06:51:58 INFO - PROCESS | 1676 | 06:52:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:52:01 INFO - PROCESS | 1676 | RSS: Main: 268169216 06:52:01 INFO - PROCESS | 1676 | 06:52:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:52:03 INFO - PROCESS | 1676 | RSS: Main: 268148736 06:52:03 INFO - PROCESS | 1676 | 06:52:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:52:07 INFO - PROCESS | 1676 | RSS: Main: 268324864 06:52:07 INFO - PROCESS | 1676 | 06:52:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:52:10 INFO - PROCESS | 1676 | RSS: Main: 268361728 06:52:10 INFO - PROCESS | 1676 | 06:52:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:52:14 INFO - PROCESS | 1676 | RSS: Main: 268292096 06:52:14 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 06:52:16 INFO - PROCESS | 1676 | RSS: Main: 270921728 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | __start_tp_report 06:52:16 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 06:52:16 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 06:52:16 INFO - PROCESS | 1676 | |i|pagename|runs| 06:52:16 INFO - PROCESS | 1676 | |0;ai-astar;113;116;107;115;115;114;109;116;114;112 06:52:16 INFO - PROCESS | 1676 | |1;audio-beat-detection;157;122;141;197;123;142;138;125;177;122 06:52:16 INFO - PROCESS | 1676 | |2;audio-dft;196;194;203;240;194;194;196;194;194;191 06:52:16 INFO - PROCESS | 1676 | |3;audio-fft;74;79;75;173;58;58;60;67;134;59 06:52:16 INFO - PROCESS | 1676 | |4;audio-oscillator;71;86;205;113;65;99;160;116;118;124 06:52:16 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;89;105;135;86;89;85;91;85;88;87 06:52:16 INFO - PROCESS | 1676 | |6;imaging-darkroom;104;151;149;95;94;107;146;94;100;109 06:52:16 INFO - PROCESS | 1676 | |7;imaging-desaturate;90;94;87;132;152;137;90;89;90;130 06:52:16 INFO - PROCESS | 1676 | |8;json-parse-financial;62;63;76;52;54;50;71;139;78;85 06:52:16 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;66;71;89;64;74;107;107;107;103;71 06:52:16 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;100;109;110;86;91;93;117;106;100;91 06:52:16 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;138;142;139;139;141;137;140;139;251;143 06:52:16 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;172;173;167;170;174;175;147;229;232;159 06:52:16 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;70;81;64;65;71;88;87;90;66;91 06:52:16 INFO - PROCESS | 1676 | __end_tp_report 06:52:16 INFO - PROCESS | 1676 | __start_cc_report 06:52:16 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1064 06:52:16 INFO - PROCESS | 1676 | __end_cc_report 06:52:16 INFO - PROCESS | 1676 | __startTimestamp1462801936528__endTimestamp 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | ------- Summary: start ------- 06:52:16 INFO - PROCESS | 1676 | Number of tests: 14 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.50 stddev:3.00 (2.6%) stddev-sans-first:3.18 06:52:16 INFO - PROCESS | 1676 | Values: 113.0 116.0 107.0 115.0 115.0 114.0 109.0 116.0 114.0 112.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:144.40 Median:141.50 stddev:25.57 (18.1%) stddev-sans-first:26.71 06:52:16 INFO - PROCESS | 1676 | Values: 157.0 122.0 141.0 197.0 123.0 142.0 138.0 125.0 177.0 122.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:199.60 Median:195.00 stddev:14.53 (7.5%) stddev-sans-first:15.35 06:52:16 INFO - PROCESS | 1676 | Values: 196.0 194.0 203.0 240.0 194.0 194.0 196.0 194.0 194.0 191.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:83.70 Median:63.50 stddev:38.68 (60.9%) stddev-sans-first:40.87 06:52:16 INFO - PROCESS | 1676 | Values: 74.0 79.0 75.0 173.0 58.0 58.0 60.0 67.0 134.0 59.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:115.70 Median:135.00 stddev:41.83 (31.0%) stddev-sans-first:41.12 06:52:16 INFO - PROCESS | 1676 | Values: 71.0 86.0 205.0 113.0 65.0 99.0 160.0 116.0 118.0 124.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:94.00 Median:87.50 stddev:15.53 (17.8%) stddev-sans-first:16.37 06:52:16 INFO - PROCESS | 1676 | Values: 89.0 105.0 135.0 86.0 89.0 85.0 91.0 85.0 88.0 87.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:150.00 stddev:23.90 (15.9%) stddev-sans-first:25.02 06:52:16 INFO - PROCESS | 1676 | Values: 104.0 151.0 149.0 95.0 94.0 107.0 146.0 94.0 100.0 109.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:109.10 Median:89.50 stddev:25.37 (28.4%) stddev-sans-first:25.96 06:52:16 INFO - PROCESS | 1676 | Values: 90.0 94.0 87.0 132.0 152.0 137.0 90.0 89.0 90.0 130.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:73.00 Median:67.00 stddev:26.01 (38.8%) stddev-sans-first:27.28 06:52:16 INFO - PROCESS | 1676 | Values: 62.0 63.0 76.0 52.0 54.0 50.0 71.0 139.0 78.0 85.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:85.90 Median:68.50 stddev:18.56 (27.1%) stddev-sans-first:18.23 06:52:16 INFO - PROCESS | 1676 | Values: 66.0 71.0 89.0 64.0 74.0 107.0 107.0 107.0 103.0 71.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:100.30 Median:101.50 stddev:10.07 (9.9%) stddev-sans-first:10.68 06:52:16 INFO - PROCESS | 1676 | Values: 100.0 109.0 110.0 86.0 91.0 93.0 117.0 106.0 100.0 91.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:140.50 stddev:35.22 (25.1%) stddev-sans-first:37.04 06:52:16 INFO - PROCESS | 1676 | Values: 138.0 142.0 139.0 139.0 141.0 137.0 140.0 139.0 251.0 143.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:173.50 stddev:28.04 (16.2%) stddev-sans-first:29.60 06:52:16 INFO - PROCESS | 1676 | Values: 172.0 173.0 167.0 170.0 174.0 175.0 147.0 229.0 232.0 159.0 06:52:16 INFO - PROCESS | 1676 | 06:52:16 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.30 Median:84.00 stddev:11.16 (13.3%) stddev-sans-first:11.52 06:52:16 INFO - PROCESS | 1676 | Values: 70.0 81.0 64.0 65.0 71.0 88.0 87.0 90.0 66.0 91.0 06:52:16 INFO - PROCESS | 1676 | -------- Summary: end -------- 06:52:16 INFO - PROCESS | 1676 | 06:52:17 INFO - TEST-INFO | 1676: exit 0 06:52:17 INFO - TEST-OK | kraken | took 62487ms 06:52:17 INFO - SUITE-END | took 1175s 06:52:17 INFO - Completed test suite (00:19:35) 06:52:17 INFO - javascript benchmark 06:52:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3484.494748261503, 3510.982445087774, 3532.646735326467, 3542.4686297055437, 3570.767900086494, 4884.0, 5887.793927212543, 5938.317093534245, 5944.554158438118, 5965.552583556229, 4774.546418090275, 5763.962486752383, 5879.412058794119, 5912.763489460427, 6014.518838492921, 5255.1329030709985, 5312.76092575835, 5377.677339359631, 5408.0, 5459.754311055992, 379.3513835168104, 5264.552513036397, 5350.946490535084, 5371.409144994067, 5393.460653934595, 5339.9733001334935, 5343.251944727761, 5366.785328586851, 5370.758315875795, 5377.381601115843, 5308.5487733542695, 5357.821279318544, 5370.462953704638, 5392.649477783971, 5392.946070539284, 5187.988342273258, 5263.86840328991, 5329.946700532983, 5363.06146424378, 5371.328583927009, 5691.117876729072, 5719.914201286984, 5737.9384813809575, 5783.074708046693, 5813.505852002585, 6097.222222222223, 6234.688265586748, 6276.968615156895, 6277.403646653561, 6277.811665649992, 6258.78094266703, 6292.528060395489, 6311.715972781236, 6316.242050953869, 6327.14583531222, 4929.852104436873, 5908.379620139866, 5953.196318497034, 6002.969985150046, 6005.219321488223, 6229.688515574203, 6315.0, 6428.8071357859335, 6482.0, 6490.253620833664, 6160.630362178284, 6178.258608966863, 6207.503399728011, 6214.658193799294, 6234.563580549318, 4941.925871111936, 5161.354830646169, 5394.109971854678, 6091.543134264948, 6189.659568723673, 4549.545045495424, 4922.483139270396, 5962.85092872673, 6225.879341718534, 6288.52836037299, 5120.743962801845, 6201.596896201732, 6283.057541368831, 6285.811425657238, 6311.495080393557, 4720.45714742809, 4946.802127914915, 5066.442691303953, 5069.188929771292, 5083.212102124176, 4471.128130014616, 4478.932816007762, 4514.88712782183, 4517.254652982221, 4568.794404251817, 5903.793367232212, 5912.556558258148, 5982.0, 5991.850203744942, 6004.129401236796, 6253.1245625612455, 6276.372362763687, 6302.684865756693, 6342.746290148342, 6347.270063942613, 6015.608985415934, 6037.1246169305205, 6044.607100538451, 6047.969760151259, 6051.962299905286, 4867.318575399447, 5286.0, 5418.2685337479725, 5484.945150548523, 5530.806421775219, 6358.237011558643, 6358.872822543523, 6372.585781924253, 6423.80728578134, 6492.350764923659, 6370.872582548416, 6372.745090196526, 6383.744650214126, 6384.680765961776, 6400.359964003563, 6505.479561635152, 6547.639879806466, 6567.146270984741, 6570.737170513317, 6577.2436169841085, 6171.691415429121, 6173.536984726074, 6205.720742566686, 6269.655168965751, 6302.212223472066, 6315.5894866833505, 6320.715567799552, 6330.39861213184, 6333.0, 6349.904751428639, 6092.543059270483, 6198.318184999556, 6232.8130156095385, 6254.843628909131, 6277.71750271248, 6119.571629985859, 6135.508280839814, 6149.585595313014, 6171.1668924694595, 6232.844178895382, 5004.47453017428, 6093.6039157454625, 6123.7244324006415, 6151.292601350902, 6207.472364848858, 6120.846978825565, 6217.906731399123, 6223.222097237845, 6245.5628106033955, 6246.312905580292, 5524.530414914777, 5865.5600829937075, 6114.38856114385, 6140.692965351804, 6146.907796382969], "value": 5712.121526937316, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.75014867640544, 512.96125346174, 525.9368875734913, 571.5513322042195, 572.4647454629924, 38774.806125969364, 39637.40543891844, 39663.40504892425, 39933.40099898504, 40059.59940400595, 40412.59587404125, 40784.18431631366, 41237.76286711396, 41321.173576528454, 41326.58673413265, 9520.952395238015, 9656.0, 9665.178459830888, 9666.516674166298, 9717.174040206592, 9579.137877591016, 9655.32412731109, 9655.710328690151, 9686.564104615309, 9693.660721874736, 9653.855192172123, 9690.75773105671, 9705.320627556073, 9716.882558505757, 9717.90282097177, 9564.282678799118, 9644.855327170062, 9645.855312170323, 9691.418514889094, 9734.805303893952, 8372.455790373606, 9270.860937085949, 9456.952715236448, 9519.714408567754, 9526.285528585384, 30156.396872062436, 38479.61520384788, 38703.612963870284, 38753.41869871954, 38793.612063879285, 3157.857896394668, 3175.04539688067, 3204.4071846708434, 3369.9503723307253, 4055.2497787909333, 27242.5913611296, 28106.297342566395, 28602.856985715145, 28915.0, 29862.25344366408, 2205.8125059369972, 2748.2167582239167, 2756.834589924611, 2836.602875597418, 2874.985625071882, 456693.0, 461209.0, 462491.0, 462757.0, 464984.0, 63412.0, 63604.04593931095, 64133.67933160398, 64224.67887660625, 64265.678671607275, 35949.0, 37475.437868431996, 37506.0, 66641.00038499426, 66997.6650116756, 16719.6656066878, 17059.31762729502, 17226.483205503853, 17250.396236131674, 17321.04734239629, 13055.673608159872, 13067.215967042008, 13132.277724725232, 13147.276899770413, 13158.473661053451, 19775.703364449546, 19906.0, 19927.402177934684, 19930.0, 19975.700364494543, 7172.852343624995, 7471.887921681179, 7568.508046976929, 7991.72028978983, 8258.174182581814, 773.6363908962778, 790.8734602463578, 841.2975165736678, 873.611242996873, 896.0636135238691, 29188.562171567442, 29798.0, 29924.401511969638, 30139.09582712522, 30409.087727368213, 1036.9637062702768, 1062.1874266186373, 1082.9350238985528, 1096.5175322857917, 1105.9612913548149, 15362.462313819186, 15394.538163855103, 15448.613784655248, 15570.610734731496, 15693.921530392276, 134194.0, 163642.0, 173219.40170897447, 173422.0, 656344.0, 58150.22023568539, 58499.41500585025, 59206.0, 60170.69914650486, 60632.0, 1432.8567143285588, 1445.7831325301288, 1473.3738161281199, 1475.2918599072505, 1477.2170749502782, 28934.27664308325, 29708.257293567833, 29771.404571908875, 29830.0, 29946.850265748533, 50763.73090672763, 57141.85716285734, 59253.0, 59807.401925981925, 61762.073568895605, 630519.0, 635376.7618133343, 638135.0, 641274.0, 641453.0, 26953.460930781668, 42149.578504214565, 42634.0, 43088.33749131359, 43100.353494696974, 44244.55755442404, 45378.54621453743, 46138.077238454374, 46301.0, 46468.53531464777, 25894.482110357312, 37408.0, 38332.0, 38786.22427551377, 39319.80340098281, 37084.88745337644, 37314.62685373111, 37550.624493754716, 37906.81046594749, 38025.429618555194, 29497.0, 37140.25719485541, 38492.230155397294, 38684.03289917774, 38839.80580097081, 26567.072384410214, 28472.0, 32961.01116966495, 33774.83112584421, 33918.66081339155, 19476.262714182798, 24068.315217934578, 24507.0, 25436.872815635805, 25539.48921021532], "value": 19397.1241169179, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.9784612923225, 388.89888628956464, 396.6350957119451, 407.03126558790115, 413.4212103055722, 15119.848801511982, 19295.710564341545, 21051.894740526313, 21052.473688157803, 21493.817840018834, 20598.794012059876, 21268.0, 21317.68023479649, 21330.57338853222, 21644.35066947994, 4836.2987366831785, 5767.9423205768035, 5920.052791553352, 5988.940110598882, 6007.609505382136, 5756.568257380693, 5922.348541660435, 5929.280508652507, 5949.940500594982, 5952.613080149798, 5878.088896221093, 5946.791862284799, 5957.523398128161, 5978.22283103196, 5985.25184351956, 5680.801171959003, 5900.085486749539, 5980.953333166678, 5984.251968503937, 5994.520438364921, 5160.380754309507, 5193.584513238932, 5209.583233341324, 5234.7644356004075, 5238.371395432534, 25843.0, 26430.339241518923, 27121.864390678118, 27153.1854044379, 27221.45557088867, 2148.0977989244557, 2148.817350525207, 2234.240358278193, 2387.9403014924765, 2586.961195582068, 15052.54842354731, 15342.463013794575, 15380.692386152326, 15442.073475591387, 15676.608084797857, 1742.8257174282596, 1775.1568005197428, 1810.248746770089, 1858.50749551369, 1901.3345329134831, 116959.0, 120259.0, 120595.99999999913, 121813.0, 123328.15007774891, 11194.0, 11429.771404571862, 11648.650540483799, 11748.060155187735, 12025.759484810254, 14420.85579144195, 14440.989130760956, 14510.637234069232, 14540.200288983995, 14580.854191458162, 8757.0, 8987.41581797181, 8992.370534062553, 9000.8649870252, 9082.046385129599, 8950.552472376356, 9002.729918102468, 9008.189262966234, 9131.452112873249, 9131.589078491485, 6926.480513961473, 6933.5493192942295, 7027.5080744348425, 7047.083879095787, 7146.428285737233, 1697.9660406792043, 1894.7157926310888, 1934.071645610115, 1939.9921081680675, 1940.6506828770675, 866.9913300867037, 988.8566157907063, 996.5366104761321, 1000.5147503460815, 1066.632011955874, 19461.12424940881, 21510.892445537986, 21518.784812151993, 21807.902332282094, 21928.89035554812, 952.9714108576753, 957.2963871554402, 980.3921568627354, 993.2302465589227, 1096.1285777806504, 8858.64565417389, 11439.714007149722, 11480.598179063692, 11498.42507874603, 11684.70788230284, 620985.0, 653815.0, 656388.0, 657699.0, 666463.0, 108514.0, 129652.0, 140549.0, 148705.0, 154511.45488545197, 1002.8796544414716, 1049.9265051446478, 1056.6090546497844, 1062.5537274344795, 1065.3288428290127, 18349.54126146857, 18670.253189872252, 18766.53083672919, 18798.906005469886, 18866.52833679114, 50514.0, 55231.0, 56273.8745225085, 56368.87262254814, 57429.0, 82442.58778706308, 83240.0, 84047.5797621008, 84414.57792710997, 86136.56931715301, 6719.496037797283, 6769.93230067693, 7980.441369104107, 7993.920060799318, 8094.433389662667, 10918.563257469612, 12533.435995380412, 12559.246445213317, 12573.811392828931, 12944.676383090498, 10729.409882456539, 10943.562257509611, 10991.780164396827, 11139.83290250663, 11200.607978720705, 10695.197860160675, 10764.40795756241, 10784.245102842726, 10894.945525272324, 10980.780384392427, 11087.611933582608, 11387.316760994367, 11487.712807179887, 11508.309501429942, 11553.13351498624, 6601.801945941629, 7998.800029999296, 8210.343172546302, 8234.547099909563, 8480.194381533744, 6715.664216789239, 8266.628001739817, 9500.61997520119, 10923.672289831318, 10999.450027498753], "value": 9878.539108947796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.4300408848724, 1606.8634166095885, 1862.5529872830525, 1943.4072607854607, 1970.9507262318432, 7886.447948643583, 8365.247127758501, 8438.535880526568, 8834.05650756834, 8863.335249856254, 8516.531590762506, 8553.914460855405, 8687.522186279752, 8736.4758114513, 8750.737477875675, 2396.2332053742807, 2413.8672373019476, 2416.734159242482, 2422.321749910028, 2427.0655797517993, 2405.2663937499055, 2412.867292298923, 2422.8546287222734, 2436.50051739393, 2450.7181674107437, 2399.6520504526834, 2404.735479097307, 2419.9395015124674, 2425.8301918865686, 2434.8904299306487, 2391.1272385579136, 2400.6159014557675, 2408.8313818032743, 2415.1064106280787, 2416.927492175229, 2338.286822519131, 2361.681173041635, 2380.964285535701, 2383.3445802404303, 2399.928002159938, 8604.31165506758, 8624.56877156146, 8732.47605143687, 8778.122187781235, 8796.912030879674, 4817.855464336076, 5281.0, 5380.946190538123, 5405.891882162374, 5411.998780225671, 3376.763626546143, 3530.452779819132, 3559.4482855157494, 3577.1951310955087, 3587.964120358789, 1027.1679939249232, 1041.536516250269, 1048.9527971241237, 1050.56926660069, 1066.4507778494083, 11492.0, 11497.425128743529, 11602.12984026193, 11634.883651163465, 11661.833816618355, 4544.454665440168, 4590.609798167126, 4686.156491831435, 4687.69529980557, 4692.0146769178855, 9219.861702074475, 9221.861672074925, 9225.031371706009, 9254.907450925404, 9257.583408746623, 5245.659032162897, 5247.107991641354, 5303.0719624066405, 5332.760025798849, 5333.093374126408, 3972.880813575597, 4358.389825424443, 4941.085899108676, 4960.454350021495, 4976.776045077981, 3798.3352913240074, 3812.694984401241, 3884.4173373994126, 3913.9608603914166, 3927.253821773911, 1194.5759255464186, 1200.8318835363057, 1213.8968187704145, 1217.6164508179895, 1230.138902768066, 936.0218571592702, 942.9858552121724, 951.1724799424545, 958.3626888119322, 980.5734505489992, 5530.5299049580435, 5757.366689664133, 5837.124431335334, 6064.241969753781, 6114.449699527064, 392.6760422651267, 445.98662040138845, 462.6160286961814, 467.21274652211133, 473.08222049224395, 4001.559828418872, 4036.233115708006, 4043.939340909889, 4071.307877660805, 4089.0, 8643.222110010129, 8826.338024648177, 9466.479343636167, 10247.436390998566, 10813.297135686313, 10432.113270372103, 10631.0, 10645.467726613639, 10670.30643008202, 10802.891971080346, 6748.59508429486, 8209.83580328402, 8268.83462330762, 8304.211099945496, 8314.0, 5802.47777700009, 5876.794312199053, 5898.203742494708, 5999.880002400015, 6127.601705889103, 9465.763355916157, 9880.505974701093, 10106.0, 10146.441945693057, 10220.386776793417, 10017.499125043865, 10100.797984040424, 10118.190544756255, 10129.0, 10208.795824083329, 5524.640898341595, 6956.130483689539, 7440.330370266485, 7503.812404689927, 7682.6926922924695, 8283.58582070882, 8329.167083291622, 8373.706920258006, 8378.329733621418, 8488.108748581557, 7284.125904891447, 7391.445641577011, 7460.0, 7495.213002634645, 7544.924550754642, 7330.743423980349, 7357.411407087313, 7392.186859445565, 7469.290417410337, 7562.773116806505, 6924.757633482804, 6987.475939304471, 7004.124484439445, 7015.6842942066705, 7036.085308909809, 4590.449146102489, 4681.882952926067, 4696.906061878812, 4811.78346974394, 5067.873303167303, 4887.3890763654545, 5047.798088076583, 5050.065737838511, 5072.9746351268, 5270.762815673227], "value": 4479.365475904839, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7772181750957, 318.3283272295458, 319.27843074651287, 325.1286552040532, 326.16664422400754, 4991.700497970124, 5247.691015952988, 5374.925014997001, 5429.4027656957705, 5433.673979561229, 4405.647548196144, 4554.954450455494, 5199.974000130003, 5402.892871780009, 5441.5102640762425, 3174.365126974614, 3265.5591495148087, 3751.636469024511, 4097.89755256118, 4138.710290279681, 3241.9027429177045, 4030.9596904031023, 4079.6940229482757, 4097.856575019875, 4124.298869192245, 3480.303939212155, 3539.4867744177077, 3870.322693528635, 3973.6821054315587, 4064.9390259146135, 3793.5827059023486, 3961.1483531040676, 4086.346184610463, 4106.7946602669745, 4117.258893399187, 3061.3877224554954, 3473.9826300868585, 3551.9289614207573, 3572.964270357315, 3579.8926032219074, 5071.239314102877, 5137.5633071188995, 5149.201873709581, 5160.845174644766, 5166.560842328407, 4063.0, 4451.888702782424, 4539.455265368144, 4574.902023514366, 4739.578126562018, 4267.162337045861, 5651.265335506358, 5651.406602306781, 5677.205191273225, 5708.6604336463215, 3844.788536630484, 3908.6286802753975, 3930.860050585327, 3946.210757848442, 3962.7820469874146, 5036.622253331015, 5229.823289759774, 5796.507296879812, 5953.737579719722, 6048.580567772882, 4004.0390306326854, 4014.3340513687076, 4030.375291829771, 4047.5952404759287, 4052.3111071117983, 4070.3894415837863, 4092.6725861930972, 4104.897377565585, 4116.115035267432, 4120.743173332163, 3989.3218152914073, 4716.504766999485, 4717.386739723814, 4754.7147171169145, 4810.350602668595, 4936.777844996984, 5460.699661518575, 5660.2075709400715, 5743.253377060955, 5786.855328616818, 1140.8516892803882, 1158.530795028015, 1169.5438778876246, 1178.3283528388909, 1256.214865708932, 747.5626758346319, 874.6676263020012, 917.6191880369705, 921.7833809054866, 933.6358820060182, 4201.68487363449, 4860.076585448754, 4918.05736733988, 5103.030424219386, 5341.65279256847, 4989.0, 5194.376674798973, 5348.866278343073, 5887.8233652990475, 5963.0, 4525.547445255514, 5290.391604965402, 5311.7078560678765, 5330.307060082165, 5336.893262134736, 2809.676887157962, 2810.2271875234474, 2925.8683359248885, 2964.333025069342, 2981.344104297051, 4913.213885778258, 4995.2526110639, 5163.889763700822, 5189.351331083614, 5918.674472903946, 5017.523335283142, 5100.877806882554, 5598.104303311532, 5634.070378387521, 6060.696920178513, 5795.333536643256, 6101.084837274533, 6125.693715314216, 6171.9691401543605, 6245.28179259391, 5348.743045384409, 5373.946260537501, 5439.660945793189, 5491.697956612425, 6019.742596526303, 4434.600885920283, 4894.1402267138255, 4990.0, 5352.652077614943, 5514.99432809128, 5377.650452720561, 5753.568482363756, 5914.970425147847, 6215.042261687554, 6263.968680156752, 3469.7918124911575, 3854.325493038651, 3861.467735049082, 3951.407288906687, 3970.7419017763755, 5921.703914804329, 5939.940600593939, 5968.970155149196, 6018.86330352736, 6020.608660437058, 4764.642651801199, 5710.628809127394, 5755.7697692093525, 5765.048766953406, 5773.595848290579, 4341.327094300388, 4826.734529600905, 5715.799947001835, 5769.480746732815, 5776.335721391925, 5634.887302254014, 5637.351704554028, 5642.576806739429, 5651.078633264709, 5662.405447427961, 4641.373414589122, 4730.030343779448, 5441.483059109379, 5594.678208543409, 5715.714214289352, 4889.682170658896, 5322.3010008045585, 5597.188407680945, 5603.80386686464, 5651.208830763779], "value": 4077.2296158968875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.9006603976384, 674.6053558668181, 739.5156172706879, 756.625470392156, 758.5486635451904, 15034.947553671247, 15507.457238996638, 15623.375064997415, 15727.84272157278, 15780.605484862885, 14337.21145337006, 14412.423503059865, 14528.273586320669, 14537.418503259856, 14606.269686515685, 757.2843662738715, 902.9661038111342, 910.995445022774, 921.8294615496121, 923.7736754495124, 711.1892442615397, 888.6756333938131, 914.5107367558358, 917.0829170829171, 922.1101636920371, 947.6304241345881, 956.5456408206117, 976.7313988653107, 983.3706427886159, 984.8227319082562, 940.501534186882, 944.1031020530489, 951.2009911674227, 973.3478569358547, 975.6682727872521, 704.883694190458, 710.5132983906041, 713.5789883968483, 728.02808250985, 730.2332550821669, 15009.849901500955, 15105.848941510554, 15108.791296696238, 15110.320035598315, 15159.848401515954, 12673.556425525154, 12685.365731713377, 12754.170978886343, 12769.233845969271, 12963.15739476931, 9155.725328240163, 9847.704568862946, 9955.55200016001, 10014.198864090855, 10251.743706407326, 2496.737842526527, 3440.896773096811, 3493.7379696522607, 3503.6496350365014, 3509.736769742254, 82869.58565207195, 83427.0, 85167.57416212879, 85529.0, 85679.57160214221, 8448.366372522085, 9249.722508324761, 9467.810643787223, 9681.225501959967, 9706.272029597809, 19586.902065489867, 20109.798902011084, 20938.162473501194, 22373.888130559568, 22472.32583022512, 5671.376148623648, 6796.898046529306, 7504.099508058958, 7553.697852085965, 7646.9617651911385, 3831.1571454280015, 3832.7125465590193, 3978.6419222270133, 3984.541777695544, 3997.0806714455257, 3260.9021729348156, 3291.3581851539197, 3344.7491438142238, 3345.9330813383594, 3363.142398688368, 548.8775454196178, 646.6992848325457, 649.250116115887, 655.3315618069543, 656.7832615236961, 472.5723220485466, 537.6021743909479, 590.2739630254811, 596.5674885707828, 602.668532307238, 7996.200379962073, 8057.6776928923355, 8162.551059691774, 8209.71266005687, 8286.958565207256, 513.599392473871, 551.3686828581252, 590.10598942602, 609.107657282075, 621.9782307619303, 3785.564660064073, 3807.276617442677, 3826.6556009959236, 3834.1564855731694, 3840.807959602009, 74221.6288918552, 77412.22587774163, 77912.61043694745, 86200.56899715461, 88356.0, 78236.0, 78909.99999999885, 79352.60323698344, 79962.60018699986, 80180.59909700415, 65508.689826204165, 67938.66030669815, 70227.64886175537, 71421.0, 72360.63819680868, 7936.880946785687, 8090.1909809018625, 8202.0, 8258.091609922783, 8286.627101780314, 30191.396172076875, 38920.02699932411, 39691.40462893117, 41741.79129104335, 44074.0, 57622.7118864403, 58445.0, 58731.412685872594, 59047.0, 61027.69486152541, 9604.183644390305, 10780.137588993019, 10989.395583242678, 11023.393713345522, 11271.098312134845, 9613.759656008655, 11843.46704398287, 11923.642290730944, 11958.64124076279, 12005.939970300093, 11417.257878237888, 11453.713657158638, 11489.54041838317, 11721.238119522544, 11749.353785541878, 11489.253198542068, 11496.137789665976, 11557.133215009077, 11918.404079795802, 11929.642110736691, 11121.165912556753, 11309.321440713584, 11317.603883864025, 11325.886741132483, 11622.0, 8815.382923195315, 9213.401128926598, 9224.0, 9334.439933604006, 9789.314747967574, 8882.245009174292, 9967.750806229902, 10078.092971632588, 10089.445080520642, 10159.79680406373], "value": 6750.760607534535, "unit": "score"}], "name": "dromaeo_css", "value": 7162.006392536939, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 107.0, 115.0, 115.0, 114.0, 109.0, 116.0, 114.0, 112.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 141.0, 197.0, 123.0, 142.0, 138.0, 125.0, 177.0, 122.0], "value": 144.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 194.0, 203.0, 240.0, 194.0, 194.0, 196.0, 194.0, 194.0, 191.0], "value": 199.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 75.0, 173.0, 58.0, 58.0, 60.0, 67.0, 134.0, 59.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 86.0, 205.0, 113.0, 65.0, 99.0, 160.0, 116.0, 118.0, 124.0], "value": 115.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 105.0, 135.0, 86.0, 89.0, 85.0, 91.0, 85.0, 88.0, 87.0], "value": 94.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 151.0, 149.0, 95.0, 94.0, 107.0, 146.0, 94.0, 100.0, 109.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 87.0, 132.0, 152.0, 137.0, 90.0, 89.0, 90.0, 130.0], "value": 109.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 76.0, 52.0, 54.0, 50.0, 71.0, 139.0, 78.0, 85.0], "value": 73.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 89.0, 64.0, 74.0, 107.0, 107.0, 107.0, 103.0, 71.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 110.0, 86.0, 91.0, 93.0, 117.0, 106.0, 100.0, 91.0], "value": 100.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 139.0, 139.0, 141.0, 137.0, 140.0, 139.0, 251.0, 143.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 167.0, 170.0, 174.0, 175.0, 147.0, 229.0, 232.0, 159.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 64.0, 65.0, 71.0, 88.0, 87.0, 90.0, 66.0, 91.0], "value": 77.3, "unit": "score"}], "name": "kraken", "value": 1641.7, "alertThreshold": 2.0}]} 06:52:17 INFO - Return code: 0 06:52:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:52:17 ERROR - # TBPL SUCCESS # 06:52:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:52:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:52:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:52:17 INFO - 2016-05-09 06:52:17.809 screenresolution[1681:10660] starting screenresolution argv=screenresolution get 06:52:17 INFO - 2016-05-09 06:52:17.811 screenresolution[1681:10660] Display 0: 1600x1200x32@60 06:52:17 INFO - 2016-05-09 06:52:17.817 screenresolution[1682:10663] starting screenresolution argv=screenresolution list 06:52:17 INFO - Available Modes on Display 0 06:52:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:52:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:52:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:52:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:52:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:52:18 INFO - Intel Iris: 06:52:18 INFO - Chipset Model: Intel Iris 06:52:18 INFO - Type: GPU 06:52:18 INFO - Bus: Built-In 06:52:18 INFO - VRAM (Dynamic, Max): 1536 MB 06:52:18 INFO - Vendor: Intel (0x8086) 06:52:18 INFO - Device ID: 0x0a2e 06:52:18 INFO - Revision ID: 0x0009 06:52:18 INFO - Displays: 06:52:18 INFO - Display: 06:52:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:52:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:52:18 INFO - Display Serial Number: 1600x1200 60 06:52:18 INFO - Main Display: Yes 06:52:18 INFO - Mirror: Off 06:52:18 INFO - Online: Yes 06:52:18 INFO - Rotation: Supported 06:52:18 INFO - Return code: 0 06:52:18 INFO - Running post-action listener: _resource_record_post_action 06:52:18 INFO - ##### 06:52:18 INFO - ##### Finished run-tests step (success) 06:52:18 INFO - ##### 06:52:18 INFO - Running post-run listener: _resource_record_post_run 06:52:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38626816; Write bytes: 699276800; Read time: 446; Write time: 2157 06:52:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378151424; Write bytes: 372352512; Read time: 28198; Write time: 684 06:52:19 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37672448; Write bytes: 300013568; Read time: 431; Write time: 1423 06:52:19 INFO - Running post-run listener: _upload_blobber_files 06:52:19 INFO - Blob upload gear active. 06:52:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:52:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:52:19 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:52:19 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:52:19 INFO - (blobuploader) - INFO - Open directory for files ... 06:52:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:52:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:52:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:52:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:52:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:52:20 INFO - (blobuploader) - INFO - Done attempting. 06:52:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:52:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:52:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:52:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:52:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:52:21 INFO - (blobuploader) - INFO - Done attempting. 06:52:21 INFO - (blobuploader) - INFO - Iteration through files over. 06:52:21 INFO - Return code: 0 06:52:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:52:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:52:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3823e88fdc3e435a9b3392bb3fd234f5a35a307bde13cbdc120364bdabe9fd0366fdbfe2c74d072be30f1adf5ddd7a4ccba83e191d4d8e70a7ebc6ff7e22edf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abe9004606fef85c4088875091210c020c73b4c56eb88d7d3bb22ac700caa2908730fc64f074e15827c01483c4d04359b9d89de5a3d8656c1c78e04b91308abc"} 06:52:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:52:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:52:21 INFO - Contents: 06:52:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3823e88fdc3e435a9b3392bb3fd234f5a35a307bde13cbdc120364bdabe9fd0366fdbfe2c74d072be30f1adf5ddd7a4ccba83e191d4d8e70a7ebc6ff7e22edf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abe9004606fef85c4088875091210c020c73b4c56eb88d7d3bb22ac700caa2908730fc64f074e15827c01483c4d04359b9d89de5a3d8656c1c78e04b91308abc"} 06:52:21 INFO - Running post-run listener: copy_logs_to_upload_dir 06:52:21 INFO - Copying logs to upload dir... 06:52:21 INFO - mkdir: /builds/slave/test/build/upload/logs 06:52:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.351508 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-09 06:52:21.776204) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:52:21.779940) ========= 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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3823e88fdc3e435a9b3392bb3fd234f5a35a307bde13cbdc120364bdabe9fd0366fdbfe2c74d072be30f1adf5ddd7a4ccba83e191d4d8e70a7ebc6ff7e22edf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abe9004606fef85c4088875091210c020c73b4c56eb88d7d3bb22ac700caa2908730fc64f074e15827c01483c4d04359b9d89de5a3d8656c1c78e04b91308abc"} build_url:https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011014 build_url: 'https://queue.taskcluster.net/v1/task/cAsXVRmZQj2qxoNJrulkZA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3823e88fdc3e435a9b3392bb3fd234f5a35a307bde13cbdc120364bdabe9fd0366fdbfe2c74d072be30f1adf5ddd7a4ccba83e191d4d8e70a7ebc6ff7e22edf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abe9004606fef85c4088875091210c020c73b4c56eb88d7d3bb22ac700caa2908730fc64f074e15827c01483c4d04359b9d89de5a3d8656c1c78e04b91308abc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 06:52:21.811302) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:52:21.812047) ========= 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.j9JOEV4DLw/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.f6TsSe8MQL/Listeners TMPDIR=/var/folders/5l/t8nv3hh175356x_rn1rqp56r00000w/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.005303 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 06:52:21.862410) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:52:21.862747) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 06:52:21.863068) ========= ========= Total master_lag: 0.18 =========